builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-002 starttime: 1472578783.46 results: success (0) buildid: 20160830091622 builduid: 4d0e4b79873644c8b930d3dcfb4ed9d9 revision: 2c00a6601ffba54b75bad5522d7b7b59e303e4fb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 10:39:43.461143) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 10:39:43.461568) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 10:39:43.462138) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 10:39:43.505964) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 10:39:43.506241) ========= 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=a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-30 10:39:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.4M=0s 2016-08-30 10:39:44 (50.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089976 ========= master_lag: 0.28 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 10:39:43.873895) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 10:39:43.874193) ========= 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=a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022358 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 10:39:43.927223) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 10:39:43.927536) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 2c00a6601ffba54b75bad5522d7b7b59e303e4fb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 2c00a6601ffba54b75bad5522d7b7b59e303e4fb --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=a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-30 10:39:44,592 truncating revision to first 12 chars 2016-08-30 10:39:44,592 Setting DEBUG logging. 2016-08-30 10:39:44,592 attempt 1/10 2016-08-30 10:39:44,592 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/2c00a6601ffb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 10:39:45,667 unpacking tar archive at: larch-2c00a6601ffb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.366389 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 10:39:45.310689) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 10:39:45.311069) ========= script_repo_revision: 2c00a6601ffba54b75bad5522d7b7b59e303e4fb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 10:39:45.311476) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 10:39:45.311776) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 10:39:45.322881) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-08-30 10:39:45.323225) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-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', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-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=a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:39:46 INFO - MultiFileLogger online at 20160830 10:39:46 in /builds/slave/test 10:39:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 10:39:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:39:46 INFO - {'append_to_log': False, 10:39:46 INFO - 'base_work_dir': '/builds/slave/test', 10:39:46 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 10:39:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:39:46 INFO - 'branch': 'Larch-Non-PGO', 10:39:46 INFO - 'buildbot_json_path': 'buildprops.json', 10:39:46 INFO - 'config_files': ('talos/linux_config.py',), 10:39:46 INFO - 'default_actions': ('clobber', 10:39:46 INFO - 'read-buildbot-config', 10:39:46 INFO - 'download-and-extract', 10:39:46 INFO - 'populate-webroot', 10:39:46 INFO - 'create-virtualenv', 10:39:46 INFO - 'install', 10:39:46 INFO - 'run-tests'), 10:39:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:39:46 INFO - 'download_minidump_stackwalk': True, 10:39:46 INFO - 'download_symbols': 'ondemand', 10:39:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:39:46 INFO - 'tooltool.py': '/tools/tooltool.py', 10:39:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:39:46 INFO - '/tools/misc-python/virtualenv.py')}, 10:39:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:39:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:39:46 INFO - 'installer_path': 'installer.exe', 10:39:46 INFO - 'log_level': 'info', 10:39:46 INFO - 'log_name': 'talos', 10:39:46 INFO - 'log_to_console': True, 10:39:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:39:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:39:46 INFO - 'opt_config_files': (), 10:39:46 INFO - 'pip_index': False, 10:39:46 INFO - 'sps_profile': False, 10:39:46 INFO - 'sps_profile_interval': 0, 10:39:46 INFO - 'suite': 'dromaeojs', 10:39:46 INFO - 'system_bits': '32', 10:39:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:39:46 INFO - 'title': 'talos-linux64-ix-002', 10:39:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:39:46 INFO - 'use_talos_json': True, 10:39:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:39:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:39:46 INFO - 'work_dir': 'build'} 10:39:46 INFO - [mozharness: 2016-08-30 17:39:46.082887Z] Running clobber step. 10:39:46 INFO - Running pre-action listener: _resource_record_pre_action 10:39:46 INFO - Running main action method: clobber 10:39:46 INFO - rmtree: /builds/slave/test/build 10:39:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:39:47 INFO - Running post-action listener: _resource_record_post_action 10:39:47 INFO - [mozharness: 2016-08-30 17:39:47.054637Z] Finished clobber step (success) 10:39:47 INFO - [mozharness: 2016-08-30 17:39:47.054739Z] Running read-buildbot-config step. 10:39:47 INFO - Running pre-action listener: _resource_record_pre_action 10:39:47 INFO - Running main action method: read_buildbot_config 10:39:47 INFO - Using buildbot properties: 10:39:47 INFO - { 10:39:47 INFO - "project": "", 10:39:47 INFO - "product": "firefox", 10:39:47 INFO - "script_repo_revision": "production", 10:39:47 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 10:39:47 INFO - "repository": "", 10:39:47 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 10:39:47 INFO - "buildid": "20160830091622", 10:39:47 INFO - "pgo_build": "False", 10:39:47 INFO - "basedir": "/builds/slave/test", 10:39:47 INFO - "buildnumber": 3, 10:39:47 INFO - "slavename": "talos-linux64-ix-002", 10:39:47 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:39:47 INFO - "platform": "ubuntu64_hw", 10:39:47 INFO - "scheduler": "tests-larch-linux64-talos", 10:39:47 INFO - "branch": "larch", 10:39:47 INFO - "repo_path": "projects/larch", 10:39:47 INFO - "revision": "2c00a6601ffba54b75bad5522d7b7b59e303e4fb", 10:39:47 INFO - "stage_platform": "linux64", 10:39:47 INFO - "builduid": "4d0e4b79873644c8b930d3dcfb4ed9d9", 10:39:47 INFO - "slavebuilddir": "test" 10:39:47 INFO - } 10:39:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 10:39:47 INFO - Running post-action listener: _resource_record_post_action 10:39:47 INFO - [mozharness: 2016-08-30 17:39:47.057205Z] Finished read-buildbot-config step (success) 10:39:47 INFO - [mozharness: 2016-08-30 17:39:47.057296Z] Running download-and-extract step. 10:39:47 INFO - Running pre-action listener: _resource_record_pre_action 10:39:47 INFO - Running main action method: download_and_extract 10:39:47 INFO - mkdir: /builds/slave/test/build/tests 10:39:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:47 INFO - trying https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 10:39:47 INFO - Downloading https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 10:39:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 10:39:47 INFO - Downloaded 1361 bytes. 10:39:47 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 10:39:47 INFO - Using the following test package requirements: 10:39:47 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 10:39:47 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:39:47 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:39:47 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:47 INFO - u'jsshell-linux-x86_64.zip'], 10:39:47 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:39:47 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 10:39:47 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:39:47 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:39:47 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:39:47 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:39:47 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:39:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:47 INFO - trying https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 10:39:47 INFO - Downloading https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 10:39:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:39:48 INFO - Downloaded 20305117 bytes. 10:39:48 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 10:39:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:39: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:52 INFO - trying https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 10:39:52 INFO - Downloading https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 10:39:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:39:53 INFO - Downloaded 20305117 bytes. 10:39:53 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 10:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:58 INFO - trying https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 10:39:58 INFO - Downloading https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 10:39:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:39:59 INFO - Downloaded 14413998 bytes. 10:39:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 10:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:59 INFO - trying https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:39:59 INFO - Downloading https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:39:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:40:01 INFO - Downloaded 55887613 bytes. 10:40:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:40:01 INFO - mkdir: /builds/slave/test/properties 10:40:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:40:01 INFO - Writing to file /builds/slave/test/properties/build_url 10:40:01 INFO - Contents: 10:40:01 INFO - build_url:https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:40:02 INFO - Running post-action listener: _resource_record_post_action 10:40:02 INFO - Running post-action listener: set_extra_try_arguments 10:40:02 INFO - [mozharness: 2016-08-30 17:40:02.945724Z] Finished download-and-extract step (success) 10:40:02 INFO - [mozharness: 2016-08-30 17:40:02.945957Z] Running populate-webroot step. 10:40:02 INFO - Running pre-action listener: _resource_record_pre_action 10:40:02 INFO - Running main action method: populate_webroot 10:40:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:40:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:40:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:40:02 INFO - '--tppagecycles', 10:40:02 INFO - '7'], 10:40:02 INFO - 'tests': ['tsvgm']}}, 10:40:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:40:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:40:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:40:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:40:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:40:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:40:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:02 INFO - 'talos_options': ['--disable-e10s'], 10:40:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:40:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:40:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:02 INFO - 'talos_options': ['--disable-e10s'], 10:40:02 INFO - 'tests': ['damp', 'tps']}, 10:40:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:02 INFO - 'tests': ['damp', 'tps']}, 10:40:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:40:02 INFO - 'tests': ['dromaeo_dom']}, 10:40:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:40:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:40:02 INFO - 'tests': ['basic_compositor_video']}, 10:40:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:40:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:40:02 INFO - 'tests': ['a11yr', 10:40:02 INFO - 'ts_paint', 10:40:02 INFO - 'tpaint', 10:40:02 INFO - 'sessionrestore', 10:40:02 INFO - 'sessionrestore_no_auto_restore', 10:40:02 INFO - 'tabpaint']}, 10:40:02 INFO - 'other-e10s': {'tests': ['a11yr', 10:40:02 INFO - 'ts_paint', 10:40:02 INFO - 'tpaint', 10:40:02 INFO - 'sessionrestore', 10:40:02 INFO - 'sessionrestore_no_auto_restore', 10:40:02 INFO - 'tabpaint']}, 10:40:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:40:02 INFO - 'ts_paint', 10:40:02 INFO - 'tpaint', 10:40:02 INFO - 'sessionrestore', 10:40:02 INFO - 'sessionrestore_no_auto_restore', 10:40:02 INFO - 'tabpaint']}, 10:40:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:40:02 INFO - 'ts_paint', 10:40:02 INFO - 'tpaint', 10:40:02 INFO - 'sessionrestore', 10:40:02 INFO - 'sessionrestore_no_auto_restore', 10:40:02 INFO - 'tabpaint']}, 10:40:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:40:02 INFO - 'tests': ['a11yr', 10:40:02 INFO - 'ts_paint', 10:40:02 INFO - 'tpaint', 10:40:02 INFO - 'sessionrestore', 10:40:02 INFO - 'sessionrestore_no_auto_restore', 10:40:02 INFO - 'tabpaint']}, 10:40:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:40:02 INFO - 'tests': ['a11yr', 10:40:02 INFO - 'ts_paint', 10:40:02 INFO - 'tpaint', 10:40:02 INFO - 'sessionrestore', 10:40:02 INFO - 'sessionrestore_no_auto_restore', 10:40:02 INFO - 'tabpaint']}, 10:40:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:40:02 INFO - 'tests': ['tsvgx', 10:40:02 INFO - 'tsvgr_opacity', 10:40:02 INFO - 'tart', 10:40:02 INFO - 'tscrollx', 10:40:02 INFO - 'cart']}, 10:40:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:40:02 INFO - 'tsvgr_opacity', 10:40:02 INFO - 'tart', 10:40:02 INFO - 'tscrollx', 10:40:02 INFO - 'cart']}, 10:40:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:02 INFO - 'talos_options': ['--disable-e10s'], 10:40:02 INFO - 'tests': ['tp5o']}, 10:40:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:02 INFO - 'tests': ['tp5o']}, 10:40:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:02 INFO - 'talos_options': ['--disable-e10s', 10:40:02 INFO - '--xperf_path', 10:40:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:40:02 INFO - 'tests': ['tp5n']}, 10:40:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:02 INFO - 'talos_options': ['--xperf_path', 10:40:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:40:02 INFO - 'tests': ['tp5n']}}, 10:40:02 INFO - 'talos.zip': {'path': '', 10:40:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:40:02 INFO - Running post-action listener: _resource_record_post_action 10:40:02 INFO - [mozharness: 2016-08-30 17:40:02.964134Z] Finished populate-webroot step (success) 10:40:02 INFO - [mozharness: 2016-08-30 17:40:02.964225Z] Running create-virtualenv step. 10:40:02 INFO - Running pre-action listener: _resource_record_pre_action 10:40:02 INFO - Running main action method: create_virtualenv 10:40:02 INFO - Creating virtualenv /builds/slave/test/build/venv 10:40:02 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 10:40:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:40:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:40:03 INFO - Using real prefix '/usr' 10:40:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:40:03 INFO - Installing distribute.............................................................................................................................................................................................done. 10:40:05 INFO - Installing pip.................done. 10:40:05 INFO - Return code: 0 10:40:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:05 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 0x7f1f39788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2601230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fb090>, '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 0x25fe9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25fee40>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', '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 10:40:05 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 10:40:05 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 10:40:05 INFO - Using env: {'DISPLAY': ':0', 10:40:05 INFO - 'HOME': '/home/cltbld', 10:40:05 INFO - 'LANG': 'en_US.UTF-8', 10:40:05 INFO - 'LANGUAGE': 'en_US:en', 10:40:05 INFO - 'LOGNAME': 'cltbld', 10:40:05 INFO - 'MAIL': '/var/mail/cltbld', 10:40:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:05 INFO - 'MOZ_NO_REMOTE': '1', 10:40:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:40:05 INFO - 'NO_EM_RESTART': '1', 10:40:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:40:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:05 INFO - 'PWD': '/builds/slave/test', 10:40:05 INFO - 'SHELL': '/bin/bash', 10:40:05 INFO - 'SHLVL': '1', 10:40:05 INFO - 'TERM': 'linux', 10:40:05 INFO - 'TMOUT': '86400', 10:40:05 INFO - 'USER': 'cltbld', 10:40:05 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', 10:40:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:05 INFO - '_': '/tools/buildbot/bin/python'} 10:40:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:05 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 10:40:05 INFO - Cleaning up... 10:40:05 INFO - Return code: 0 10:40:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:05 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 0x7f1f39788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2601230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fb090>, '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 0x25fe9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25fee40>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', '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 10:40:05 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 10:40:05 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 10:40:05 INFO - Using env: {'DISPLAY': ':0', 10:40:05 INFO - 'HOME': '/home/cltbld', 10:40:05 INFO - 'LANG': 'en_US.UTF-8', 10:40:05 INFO - 'LANGUAGE': 'en_US:en', 10:40:05 INFO - 'LOGNAME': 'cltbld', 10:40:05 INFO - 'MAIL': '/var/mail/cltbld', 10:40:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:05 INFO - 'MOZ_NO_REMOTE': '1', 10:40:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:40:05 INFO - 'NO_EM_RESTART': '1', 10:40:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:40:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:05 INFO - 'PWD': '/builds/slave/test', 10:40:05 INFO - 'SHELL': '/bin/bash', 10:40:05 INFO - 'SHLVL': '1', 10:40:05 INFO - 'TERM': 'linux', 10:40:05 INFO - 'TMOUT': '86400', 10:40:05 INFO - 'USER': 'cltbld', 10:40:05 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', 10:40:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:05 INFO - '_': '/tools/buildbot/bin/python'} 10:40:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:05 INFO - Downloading/unpacking psutil>=3.1.1 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:40:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:40:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:40:07 INFO - Installing collected packages: psutil 10:40:07 INFO - Running setup.py install for psutil 10:40:08 INFO - building 'psutil._psutil_linux' extension 10:40:08 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 10:40:08 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 10:40:08 INFO - building 'psutil._psutil_posix' extension 10:40:08 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 10:40:08 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 10:40:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:40:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:40:08 INFO - Successfully installed psutil 10:40:08 INFO - Cleaning up... 10:40:08 INFO - Return code: 0 10:40:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:08 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 0x7f1f39788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2601230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fb090>, '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 0x25fe9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25fee40>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', '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 10:40:08 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 10:40:08 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 10:40:08 INFO - Using env: {'DISPLAY': ':0', 10:40:08 INFO - 'HOME': '/home/cltbld', 10:40:08 INFO - 'LANG': 'en_US.UTF-8', 10:40:08 INFO - 'LANGUAGE': 'en_US:en', 10:40:08 INFO - 'LOGNAME': 'cltbld', 10:40:08 INFO - 'MAIL': '/var/mail/cltbld', 10:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:08 INFO - 'MOZ_NO_REMOTE': '1', 10:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:40:08 INFO - 'NO_EM_RESTART': '1', 10:40:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:08 INFO - 'PWD': '/builds/slave/test', 10:40:08 INFO - 'SHELL': '/bin/bash', 10:40:08 INFO - 'SHLVL': '1', 10:40:08 INFO - 'TERM': 'linux', 10:40:08 INFO - 'TMOUT': '86400', 10:40:08 INFO - 'USER': 'cltbld', 10:40:08 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', 10:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:08 INFO - '_': '/tools/buildbot/bin/python'} 10:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:40:08 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 10:40:08 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 10:40:08 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 10:40:08 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 10:40:08 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 10:40:08 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 10:40:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:40:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:40:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:40:10 INFO - Installing collected packages: mozsystemmonitor 10:40:10 INFO - Running setup.py install for mozsystemmonitor 10:40:10 INFO - Successfully installed mozsystemmonitor 10:40:10 INFO - Cleaning up... 10:40:10 INFO - Return code: 0 10:40:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:40:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:10 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 0x7f1f39788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2601230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fb090>, '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 0x25fe9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25fee40>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', '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 10:40:10 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 10:40:10 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 10:40:10 INFO - Using env: {'DISPLAY': ':0', 10:40:10 INFO - 'HOME': '/home/cltbld', 10:40:10 INFO - 'LANG': 'en_US.UTF-8', 10:40:10 INFO - 'LANGUAGE': 'en_US:en', 10:40:10 INFO - 'LOGNAME': 'cltbld', 10:40:10 INFO - 'MAIL': '/var/mail/cltbld', 10:40:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:10 INFO - 'MOZ_NO_REMOTE': '1', 10:40:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:40:10 INFO - 'NO_EM_RESTART': '1', 10:40:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:40:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:10 INFO - 'PWD': '/builds/slave/test', 10:40:10 INFO - 'SHELL': '/bin/bash', 10:40:10 INFO - 'SHLVL': '1', 10:40:10 INFO - 'TERM': 'linux', 10:40:10 INFO - 'TMOUT': '86400', 10:40:10 INFO - 'USER': 'cltbld', 10:40:10 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', 10:40:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:10 INFO - '_': '/tools/buildbot/bin/python'} 10:40:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:11 INFO - Downloading/unpacking jsonschema==2.5.1 10:40:11 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 10:40:11 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 10:40:11 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 10:40:11 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 10:40:11 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 10:40:11 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 10:40:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:40:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:40:12 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 10:40:12 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 10:40:12 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 10:40:12 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 10:40:12 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 10:40:12 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 10:40:13 INFO - Downloading functools32-3.2.3-2.tar.gz 10:40:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:40:13 INFO - warning: no files found matching '*.txt' 10:40:13 INFO - no previously-included directories found matching 'build' 10:40:13 INFO - no previously-included directories found matching 'dist' 10:40:13 INFO - no previously-included directories found matching '.git*' 10:40:13 INFO - Installing collected packages: jsonschema, functools32 10:40:13 INFO - Running setup.py install for functools32 10:40:13 INFO - warning: no files found matching '*.txt' 10:40:13 INFO - no previously-included directories found matching 'build' 10:40:13 INFO - no previously-included directories found matching 'dist' 10:40:13 INFO - no previously-included directories found matching '.git*' 10:40:13 INFO - Successfully installed jsonschema functools32 10:40:13 INFO - Cleaning up... 10:40:13 INFO - Return code: 0 10:40:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1f39788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2601230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fb090>, '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 0x25fe9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25fee40>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', '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 10:40:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:40:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:40:13 INFO - Using env: {'DISPLAY': ':0', 10:40:13 INFO - 'HOME': '/home/cltbld', 10:40:13 INFO - 'LANG': 'en_US.UTF-8', 10:40:13 INFO - 'LANGUAGE': 'en_US:en', 10:40:13 INFO - 'LOGNAME': 'cltbld', 10:40:13 INFO - 'MAIL': '/var/mail/cltbld', 10:40:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:13 INFO - 'MOZ_NO_REMOTE': '1', 10:40:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:40:13 INFO - 'NO_EM_RESTART': '1', 10:40:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:40:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:13 INFO - 'PWD': '/builds/slave/test', 10:40:13 INFO - 'SHELL': '/bin/bash', 10:40:13 INFO - 'SHLVL': '1', 10:40:13 INFO - 'TERM': 'linux', 10:40:13 INFO - 'TMOUT': '86400', 10:40:13 INFO - 'USER': 'cltbld', 10:40:13 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', 10:40:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:13 INFO - '_': '/tools/buildbot/bin/python'} 10:40:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:40:13 INFO - Cleaning up... 10:40:13 INFO - Return code: 0 10:40:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:13 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 0x7f1f39788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2601230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fb090>, '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 0x25fe9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25fee40>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', '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 10:40:13 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 10:40:13 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 10:40:13 INFO - Using env: {'DISPLAY': ':0', 10:40:13 INFO - 'HOME': '/home/cltbld', 10:40:13 INFO - 'LANG': 'en_US.UTF-8', 10:40:13 INFO - 'LANGUAGE': 'en_US:en', 10:40:13 INFO - 'LOGNAME': 'cltbld', 10:40:13 INFO - 'MAIL': '/var/mail/cltbld', 10:40:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:13 INFO - 'MOZ_NO_REMOTE': '1', 10:40:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:40:13 INFO - 'NO_EM_RESTART': '1', 10:40:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:40:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:13 INFO - 'PWD': '/builds/slave/test', 10:40:13 INFO - 'SHELL': '/bin/bash', 10:40:13 INFO - 'SHLVL': '1', 10:40:13 INFO - 'TERM': 'linux', 10:40:13 INFO - 'TMOUT': '86400', 10:40:13 INFO - 'USER': 'cltbld', 10:40:13 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', 10:40:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:13 INFO - '_': '/tools/buildbot/bin/python'} 10:40:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:13 INFO - Downloading/unpacking blobuploader==1.2.4 10:40:13 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 10:40:13 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 10:40:13 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 10:40:13 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 10:40:13 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 10:40:13 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 10:40:15 INFO - Downloading blobuploader-1.2.4.tar.gz 10:40:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:40:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:40:15 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 10:40:15 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 10:40:15 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 10:40:15 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 10:40:15 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 10:40:15 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 10:40:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:40:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:40:16 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 10:40:16 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 10:40:16 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 10:40:16 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 10:40:16 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 10:40:16 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 10:40:16 INFO - Downloading docopt-0.6.1.tar.gz 10:40:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:40:16 INFO - Installing collected packages: blobuploader, requests, docopt 10:40:16 INFO - Running setup.py install for blobuploader 10:40:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:40:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:40:16 INFO - Running setup.py install for requests 10:40:16 INFO - Running setup.py install for docopt 10:40:17 INFO - Successfully installed blobuploader requests docopt 10:40:17 INFO - Cleaning up... 10:40:17 INFO - Return code: 0 10:40:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:17 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 0x7f1f39788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2601230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fb090>, '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 0x25fe9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25fee40>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', '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 10:40:17 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 10:40:17 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 10:40:17 INFO - Using env: {'DISPLAY': ':0', 10:40:17 INFO - 'HOME': '/home/cltbld', 10:40:17 INFO - 'LANG': 'en_US.UTF-8', 10:40:17 INFO - 'LANGUAGE': 'en_US:en', 10:40:17 INFO - 'LOGNAME': 'cltbld', 10:40:17 INFO - 'MAIL': '/var/mail/cltbld', 10:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:17 INFO - 'MOZ_NO_REMOTE': '1', 10:40:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:40:17 INFO - 'NO_EM_RESTART': '1', 10:40:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:17 INFO - 'PWD': '/builds/slave/test', 10:40:17 INFO - 'SHELL': '/bin/bash', 10:40:17 INFO - 'SHLVL': '1', 10:40:17 INFO - 'TERM': 'linux', 10:40:17 INFO - 'TMOUT': '86400', 10:40:17 INFO - 'USER': 'cltbld', 10:40:17 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', 10:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:17 INFO - '_': '/tools/buildbot/bin/python'} 10:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:40:17 INFO - Running setup.py (path:/tmp/pip-a33I1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:40:17 INFO - Running setup.py (path:/tmp/pip-JfZMgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:40:17 INFO - Running setup.py (path:/tmp/pip-E7hcrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:40:17 INFO - Running setup.py (path:/tmp/pip-XhXUQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:40:17 INFO - Running setup.py (path:/tmp/pip-g6p81u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:40:17 INFO - Running setup.py (path:/tmp/pip-uy1Ujh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:40:17 INFO - Running setup.py (path:/tmp/pip-iShY3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:40:17 INFO - Running setup.py (path:/tmp/pip-1fO2d9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:40:17 INFO - Running setup.py (path:/tmp/pip-ip05bI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:40:17 INFO - Running setup.py (path:/tmp/pip-Kla7Jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:40:18 INFO - Running setup.py (path:/tmp/pip-1vH4UF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:40:18 INFO - Running setup.py (path:/tmp/pip-WL16QO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:40:18 INFO - Running setup.py (path:/tmp/pip-RYZrM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:40:18 INFO - Running setup.py (path:/tmp/pip-A1pCv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:40:18 INFO - Running setup.py (path:/tmp/pip-0hCUsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:40:18 INFO - Running setup.py (path:/tmp/pip-z2RN0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:40:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:40:18 INFO - Running setup.py (path:/tmp/pip-i1m08X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:40:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:40:18 INFO - Running setup.py install for manifestparser 10:40:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:40:18 INFO - Running setup.py install for mozcrash 10:40:18 INFO - Running setup.py install for mozdebug 10:40:18 INFO - Running setup.py install for mozdevice 10:40:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:40:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:40:18 INFO - Running setup.py install for mozfile 10:40:19 INFO - Running setup.py install for mozhttpd 10:40:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:40:19 INFO - Running setup.py install for mozinfo 10:40:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:40:19 INFO - Running setup.py install for mozInstall 10:40:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:40:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:40:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:40:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:40:19 INFO - Running setup.py install for mozleak 10:40:19 INFO - Running setup.py install for mozlog 10:40:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:40:19 INFO - Running setup.py install for moznetwork 10:40:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:40:19 INFO - Running setup.py install for mozprocess 10:40:19 INFO - Running setup.py install for mozprofile 10:40:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:40:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:40:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:40:19 INFO - Running setup.py install for mozrunner 10:40:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:40:20 INFO - Running setup.py install for mozscreenshot 10:40:20 INFO - Running setup.py install for moztest 10:40:20 INFO - Running setup.py install for mozversion 10:40:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:40:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:40:20 INFO - Cleaning up... 10:40:20 INFO - Return code: 0 10:40:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:40:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:20 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 0x7f1f39788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2601230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fb090>, '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 0x25fe9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25fee40>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', '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 10:40:20 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 10:40:20 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 10:40:20 INFO - Using env: {'DISPLAY': ':0', 10:40:20 INFO - 'HOME': '/home/cltbld', 10:40:20 INFO - 'LANG': 'en_US.UTF-8', 10:40:20 INFO - 'LANGUAGE': 'en_US:en', 10:40:20 INFO - 'LOGNAME': 'cltbld', 10:40:20 INFO - 'MAIL': '/var/mail/cltbld', 10:40:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:20 INFO - 'MOZ_NO_REMOTE': '1', 10:40:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:40:20 INFO - 'NO_EM_RESTART': '1', 10:40:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:40:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:20 INFO - 'PWD': '/builds/slave/test', 10:40:20 INFO - 'SHELL': '/bin/bash', 10:40:20 INFO - 'SHLVL': '1', 10:40:20 INFO - 'TERM': 'linux', 10:40:20 INFO - 'TMOUT': '86400', 10:40:20 INFO - 'USER': 'cltbld', 10:40:20 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', 10:40:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:20 INFO - '_': '/tools/buildbot/bin/python'} 10:40:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:40:20 INFO - Running setup.py (path:/tmp/pip-_dpfF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:40:20 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)) 10:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:40:20 INFO - Running setup.py (path:/tmp/pip-ubujVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:40:20 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)) 10:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:40:20 INFO - Running setup.py (path:/tmp/pip-6BTQz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:40:20 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)) 10:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:40:20 INFO - Running setup.py (path:/tmp/pip-LTrFdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:40:20 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)) 10:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:40:20 INFO - Running setup.py (path:/tmp/pip-2N5Rtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:40:20 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)) 10:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:40:20 INFO - Running setup.py (path:/tmp/pip-9m_QBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:40:20 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)) 10:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:40:21 INFO - Running setup.py (path:/tmp/pip-oSddjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:40:21 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)) 10:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:40:21 INFO - Running setup.py (path:/tmp/pip-MAzIZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:40:21 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)) 10:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:40:21 INFO - Running setup.py (path:/tmp/pip-iMlt2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:40:21 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)) 10:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:40:21 INFO - Running setup.py (path:/tmp/pip-TEMHS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:40:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:40:21 INFO - Running setup.py (path:/tmp/pip-EOm9rM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:40:21 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)) 10:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:40:21 INFO - Running setup.py (path:/tmp/pip-8bNHfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:40:21 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)) 10:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:40:21 INFO - Running setup.py (path:/tmp/pip-5slWo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:40:21 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)) 10:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:40:21 INFO - Running setup.py (path:/tmp/pip-u7dYDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:40:21 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)) 10:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:40:21 INFO - Running setup.py (path:/tmp/pip-Sc_RaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:40:21 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)) 10:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:40:21 INFO - Running setup.py (path:/tmp/pip-I0QTin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:40:21 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)) 10:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:40:21 INFO - Running setup.py (path:/tmp/pip-X4Du12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:40:21 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)) 10:40:21 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)) 10:40:21 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)) 10:40:21 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)) 10:40:21 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)) 10:40:21 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)) 10:40:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:40:21 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 10:40:21 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 10:40:21 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 10:40:21 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 10:40:21 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 10:40:21 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 10:40:23 INFO - Downloading blessings-1.6.tar.gz 10:40:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:40:23 INFO - Installing collected packages: blessings 10:40:23 INFO - Running setup.py install for blessings 10:40:23 INFO - Successfully installed blessings 10:40:23 INFO - Cleaning up... 10:40:23 INFO - Return code: 0 10:40:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:40:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:40:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:40:24 INFO - Reading from file tmpfile_stdout 10:40:24 INFO - Current package versions: 10:40:24 INFO - argparse == 1.2.1 10:40:24 INFO - blessings == 1.6 10:40:24 INFO - blobuploader == 1.2.4 10:40:24 INFO - docopt == 0.6.1 10:40:24 INFO - functools32 == 3.2.3-2 10:40:24 INFO - jsonschema == 2.5.1 10:40:24 INFO - manifestparser == 1.1 10:40:24 INFO - mozInstall == 1.12 10:40:24 INFO - mozcrash == 0.17 10:40:24 INFO - mozdebug == 0.1 10:40:24 INFO - mozdevice == 0.48 10:40:24 INFO - mozfile == 1.2 10:40:24 INFO - mozhttpd == 0.7 10:40:24 INFO - mozinfo == 0.9 10:40:24 INFO - mozleak == 0.1 10:40:24 INFO - mozlog == 3.3 10:40:24 INFO - moznetwork == 0.27 10:40:24 INFO - mozprocess == 0.23 10:40:24 INFO - mozprofile == 0.28 10:40:24 INFO - mozrunner == 6.12 10:40:24 INFO - mozscreenshot == 0.1 10:40:24 INFO - mozsystemmonitor == 0.3 10:40:24 INFO - moztest == 0.7 10:40:24 INFO - mozversion == 1.4 10:40:24 INFO - psutil == 3.1.1 10:40:24 INFO - requests == 1.2.3 10:40:24 INFO - wsgiref == 0.1.2 10:40:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:40: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:24 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 0x7f1f39788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2601230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fb090>, '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 0x25fe9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25fee40>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', '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 10:40:24 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 10:40:24 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 10:40:24 INFO - Using env: {'DISPLAY': ':0', 10:40:24 INFO - 'HOME': '/home/cltbld', 10:40:24 INFO - 'LANG': 'en_US.UTF-8', 10:40:24 INFO - 'LANGUAGE': 'en_US:en', 10:40:24 INFO - 'LOGNAME': 'cltbld', 10:40:24 INFO - 'MAIL': '/var/mail/cltbld', 10:40:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:24 INFO - 'MOZ_NO_REMOTE': '1', 10:40:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:40:24 INFO - 'NO_EM_RESTART': '1', 10:40:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:40:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:24 INFO - 'PWD': '/builds/slave/test', 10:40:24 INFO - 'SHELL': '/bin/bash', 10:40:24 INFO - 'SHLVL': '1', 10:40:24 INFO - 'TERM': 'linux', 10:40:24 INFO - 'TMOUT': '86400', 10:40:24 INFO - 'USER': 'cltbld', 10:40:24 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', 10:40:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:24 INFO - '_': '/tools/buildbot/bin/python'} 10:40:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:24 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)) 10:40:24 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)) 10:40:24 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)) 10:40:24 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)) 10:40:24 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)) 10:40:24 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)) 10:40:24 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)) 10:40:24 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)) 10:40:24 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)) 10:40:24 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)) 10:40:24 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)) 10:40:24 INFO - Cleaning up... 10:40:24 INFO - Return code: 0 10:40:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:40: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:24 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 0x7f1f39788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2601230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fb090>, '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 0x25fe9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25fee40>, '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': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', '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 10:40:24 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 10:40:24 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 10:40:24 INFO - Using env: {'DISPLAY': ':0', 10:40:24 INFO - 'HOME': '/home/cltbld', 10:40:24 INFO - 'LANG': 'en_US.UTF-8', 10:40:24 INFO - 'LANGUAGE': 'en_US:en', 10:40:24 INFO - 'LOGNAME': 'cltbld', 10:40:24 INFO - 'MAIL': '/var/mail/cltbld', 10:40:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:24 INFO - 'MOZ_NO_REMOTE': '1', 10:40:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:40:24 INFO - 'NO_EM_RESTART': '1', 10:40:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:40:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:24 INFO - 'PWD': '/builds/slave/test', 10:40:24 INFO - 'SHELL': '/bin/bash', 10:40:24 INFO - 'SHLVL': '1', 10:40:24 INFO - 'TERM': 'linux', 10:40:24 INFO - 'TMOUT': '86400', 10:40:24 INFO - 'USER': 'cltbld', 10:40:24 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', 10:40:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:24 INFO - '_': '/tools/buildbot/bin/python'} 10:40:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:40:24 INFO - Cleaning up... 10:40:24 INFO - Return code: 0 10:40:24 INFO - Running post-action listener: _resource_record_post_action 10:40:24 INFO - Running post-action listener: _start_resource_monitoring 10:40:24 INFO - Starting resource monitoring. 10:40:24 INFO - [mozharness: 2016-08-30 17:40:24.642367Z] Finished create-virtualenv step (success) 10:40:24 INFO - [mozharness: 2016-08-30 17:40:24.642824Z] Running install step. 10:40:24 INFO - Running pre-action listener: _resource_record_pre_action 10:40:24 INFO - Running main action method: install 10:40:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:40:24 INFO - Reading from file tmpfile_stdout 10:40:24 INFO - Detecting whether we're running mozinstall >=1.0... 10:40:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:40:24 INFO - Reading from file tmpfile_stdout 10:40:24 INFO - Output received: 10:40:24 INFO - Usage: mozinstall [options] installer 10:40:24 INFO - Options: 10:40:24 INFO - -h, --help show this help message and exit 10:40:24 INFO - -d DEST, --destination=DEST 10:40:24 INFO - Directory to install application into. [default: 10:40:24 INFO - "/builds/slave/test"] 10:40:24 INFO - --app=APP Application being installed. [default: firefox] 10:40:24 INFO - mkdir: /builds/slave/test/build/application 10:40:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 10:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 10:40:41 INFO - Reading from file tmpfile_stdout 10:40:41 INFO - Output received: 10:40:41 INFO - /builds/slave/test/build/application/firefox/firefox 10:40:41 INFO - Running post-action listener: _resource_record_post_action 10:40:41 INFO - [mozharness: 2016-08-30 17:40:41.086583Z] Finished install step (success) 10:40:41 INFO - [mozharness: 2016-08-30 17:40:41.086865Z] Running run-tests step. 10:40:41 INFO - Running pre-action listener: _resource_record_pre_action 10:40:41 INFO - Running main action method: run_tests 10:40:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:40:41 INFO - Python 2.7.3 10:40:41 INFO - Return code: 0 10:40:41 INFO - grabbing minidump binary from tooltool 10:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:41 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 0x25fb090>, '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 0x25fe9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25fee40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:40:41 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 10:40:41 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 10:40:41 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 10:40:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:40:41 INFO - Return code: 0 10:40:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:40:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:40:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:40:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:40:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:40:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 10:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --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 10:40:41 INFO - Using env: {'DISPLAY': ':0', 10:40:41 INFO - 'HOME': '/home/cltbld', 10:40:41 INFO - 'LANG': 'en_US.UTF-8', 10:40:41 INFO - 'LANGUAGE': 'en_US:en', 10:40:41 INFO - 'LOGNAME': 'cltbld', 10:40:41 INFO - 'MAIL': '/var/mail/cltbld', 10:40:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:40:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:41 INFO - 'MOZ_NO_REMOTE': '1', 10:40:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:40:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:40:41 INFO - 'NO_EM_RESTART': '1', 10:40:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:41 INFO - 'PWD': '/builds/slave/test', 10:40:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:40:41 INFO - 'SHELL': '/bin/bash', 10:40:41 INFO - 'SHLVL': '1', 10:40:41 INFO - 'TERM': 'linux', 10:40:41 INFO - 'TMOUT': '86400', 10:40:41 INFO - 'USER': 'cltbld', 10:40:41 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461', 10:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:41 INFO - '_': '/tools/buildbot/bin/python'} 10:40:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 10:40:41 INFO - mozversion application_buildid: 20160830091622 10:40:41 INFO - mozversion application_changeset: 2c00a6601ffba54b75bad5522d7b7b59e303e4fb 10:40:41 INFO - mozversion application_display_name: Nightly 10:40:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:40:41 INFO - mozversion application_name: Firefox 10:40:41 INFO - mozversion application_remotingname: firefox 10:40:41 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 10:40:41 INFO - mozversion application_vendor: Mozilla 10:40:41 INFO - mozversion application_version: 51.0a1 10:40:41 INFO - mozversion platform_buildid: 20160830091622 10:40:41 INFO - mozversion platform_changeset: 2c00a6601ffba54b75bad5522d7b7b59e303e4fb 10:40:41 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 10:40:41 INFO - mozversion platform_version: 51.0a1 10:40:41 INFO - using testdate: 1472578841 10:40:41 INFO - actual date: 1472578841 10:40:41 INFO - starting webserver on 'localhost:45087' 10:40:41 INFO - SUITE-START | Running 2 tests 10:40:41 INFO - TEST-START | dromaeo_css 10:40:41 INFO - operating with platform_type : linux_ 10:40:41 INFO - Initialising browser for dromaeo_css test... 10:40:41 INFO - TEST-INFO | started process 25580 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_7ytI9/profile http://localhost:45087/getInfo.html) 10:40:46 INFO - PROCESS | 25580 | __metrics Screen width/height:1600/1200 10:40:46 INFO - PROCESS | 25580 | colorDepth:24 10:40:46 INFO - PROCESS | 25580 | Browser inner width/height: 1024/697 10:40:46 INFO - PROCESS | 25580 | __metrics 10:40:46 INFO - PROCESS | 25580 | 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" 10:40:46 INFO - PROCESS | 25580 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:40:47 INFO - PROCESS | 25580 | console.error: PushService: 10:40:47 INFO - PROCESS | 25580 | stateChangeProcessEnqueue: Error transitioning state 10:40:47 INFO - PROCESS | 25580 | UnknownError 10:40:47 INFO - TEST-INFO | 25580: exit 0 10:40:47 INFO - Browser initialized. 10:40:47 INFO - Running cycle 1/1 for dromaeo_css test... 10:40:47 INFO - TEST-INFO | started process 25660 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_7ytI9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:41:00 INFO - PROCESS | 25660 | RSS: Main: 192692224 10:41:00 INFO - PROCESS | 25660 | 10:41:51 INFO - PROCESS | 25660 | 1472578911826 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:44:04 INFO - PROCESS | 25660 | Cycle 1(1): loaded http://localhost:45087/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45087/tests/dromaeo/cssquery-ext.html) 10:44:05 INFO - PROCESS | 25660 | RSS: Main: 291725312 10:44:05 INFO - PROCESS | 25660 | 10:47:08 INFO - PROCESS | 25660 | Cycle 1(1): loaded http://localhost:45087/tests/dromaeo/cssquery-ext.html (next: http://localhost:45087/tests/dromaeo/cssquery-jquery.html) 10:47:09 INFO - PROCESS | 25660 | RSS: Main: 279756800 10:47:09 INFO - PROCESS | 25660 | 10:50:11 INFO - PROCESS | 25660 | Cycle 1(1): loaded http://localhost:45087/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45087/tests/dromaeo/cssquery-mootools.html) 10:50:12 INFO - PROCESS | 25660 | RSS: Main: 259481600 10:50:12 INFO - PROCESS | 25660 | 10:53:14 INFO - PROCESS | 25660 | Cycle 1(1): loaded http://localhost:45087/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45087/tests/dromaeo/cssquery-prototype.html) 10:53:15 INFO - PROCESS | 25660 | RSS: Main: 219000832 10:53:15 INFO - PROCESS | 25660 | 10:56:18 INFO - PROCESS | 25660 | Cycle 1(1): loaded http://localhost:45087/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45087/tests/dromaeo/cssquery-yui.html) 10:56:18 INFO - PROCESS | 25660 | RSS: Main: 219185152 10:56:18 INFO - PROCESS | 25660 | 10:59:21 INFO - PROCESS | 25660 | Cycle 1(1): loaded http://localhost:45087/tests/dromaeo/cssquery-yui.html (next: http://localhost:45087/tests/dromaeo/cssquery-dojo.html) 10:59:22 INFO - PROCESS | 25660 | RSS: Main: 310747136 10:59:22 INFO - PROCESS | 25660 | 10:59:22 INFO - PROCESS | 25660 | __start_tp_report 10:59:22 INFO - PROCESS | 25660 | _x_x_mozilla_page_load 10:59:22 INFO - PROCESS | 25660 | _x_x_mozilla_page_load_details 10:59:22 INFO - PROCESS | 25660 | |i|pagename|runs| 10:59:22 INFO - PROCESS | 25660 | |0;dojo.html;2597.2467984284553;2615.4507553413787;2636.78905687545;2670.893164273429;2690.1257091445286;4461.843835465759;4606.953930460694;4628.421447319085;4723;4788.209945359016;4640.883977900554;4675.158471475142;4699.271612899997;4699.929501057479;4723.385959825227;4231.576842315759;4240.469941257343;4306.375575541545;4369.584889435499;4487.304467807495;4370.3007518797;4431.468223813147;4455.086707225011;4455.443069616298;4458.843940462085;4203.4115223868375;4260.637845783112;4368.7815609219415;4387.495438024636;4481.731096134226;4306.8277268909205;4314.331278651783;4316.69783115179;4317.546657600969;4320.027993701392;4212.7682977436225;4218.2196293685765;4220.092680073768;4248.065425606362;4258.169656916903;4583.747893865836;4675.7895894684825;4689.413823272091;4690.788914498856;4697.271922851964;4777.522247775195;5019.447860735316;5020.799168033311;5028.4971502849785;5108.770105345268;5026.447090820006;5069.569086627641;5098.515641014098;5112.821051263188;5116.565091967187;4755.072760811643;4758.78585463655;4773.284007398848;4776.068666609977;4832.130216560982;4958.578520825698;5019.422766381913;5201.895962080813;5208.869778255498;5214.217867319857;4843.418789745253;4852.684575502581;4883.438404583448;4895.7062576245535;4977.427595826526;4991.700497970133;4995.450500444948;5007.198848184273;5050.974745126325;5051.82318618854;4931.3835770528685;4940.184869496493;4943.802247910044;4963.305137280783;4990.30135780991;4843.128236917389;4904.092742842586;4918.655694101379;4975.776090075955;5002.474740152305;4344.044310251739;4350.173467041315;4390.3414487827085;4401.867943961686;4403.889902752393;3790.033541446913;3796.772193668389;3797.886063418102;3828.2343531293855;3840.462335273064;4239.40648309237;4333;4367.082912588328;4377.474703035656;4418.359337895987;4978.850634480971;5081.28861959326;5088.313077734532;5098.515641014098;5100.56645185156;4360;4361.214981303396;4369.71596846204;4411.073674528321;4434.024514606781;4544.204764166324;4564.977175114103;4578.03861189154;4578.1301552705545;4659.8136074557315;4608.539146085364;4706.058788242297;4735.076660051257;4755.857324280277;4768.928466072943;5071.315372424676;5077;5079.746012699424;5121.103806833864;5134.435212126588;4768.785404656729;4774.95225047745;4778.689385189951;4780.187368147493;4834.661573689948;4758.857234282977;4880.755962201947;4943.30793688891;4981.651284410202;5056.62075344358;5022.246663000433;5022.522860328263;5026.120428924996;5028.220625803006;5057.74711264428;4866.829660961849;4893.363862697826;4898.118338699069;4903.485134060872;4945.208766597327;4734.976325118352;4791.6885402450125;4836.95163048365;4908.730019848943;4957.82647607332;4779.784909679003;4785.090832741768;4786.090642777861;4829.492903245249;4839.177339852163;4902.730349830794;4912.754362281943;4914.9017019658695;4919.852404427873;4968.478309777566;4649.953500464952;4680.976595117003;4774.21225497789;4817.975910120567;4836.250381190921 10:59:22 INFO - PROCESS | 25660 | |1;ext.html;571.0976656882127;573.4179807495393;577.6851615869351;584.4535359438925;585.0580565289882;27854.164375068754;30504.389912201743;30574.23564410888;37919.62080379196;39541.406878896836;39242.21515569694;39662.60337396625;39673.20653586926;39689.603103968955;39779.60220397795;7573.280538348862;7679.8080047998965;7721.459497835153;7725.111612164589;7733.767986960372;7337.0828646419195;7520.435967302446;7529.209433009537;7629.160792312841;7710.036245469317;9728.367656102331;9991.600335986552;9995.70012899614;10274.510196021572;10303.433311167882;9822.557984890662;9875.753106172331;9904.851427228597;9909.504524773733;9914.752131196741;9759.756006099833;9810.656627018092;9822.508874556244;9885.505724713807;9902.851457228147;37005.81497092497;37223.441648375294;37491.62508374909;37502.24995500102;37508;2966.688497707731;3030.8181509109304;3200.359928014383;3240.96759032409;3586.28274345132;27573;27654.170374888785;27667.308317292227;28444.857775711196;29235.85382073076;3131.9530207046914;3181.1569933967517;3186.713195812365;3207.8877239296744;3221.3074189049466;241029;241083;243449;243942;245259;49711;50002.249966250536;50075.24887126696;50099.24851127236;50100.74949625228;31693.366132677216;32462.35075298528;32557.67442325594;34071.31857362839;34078.148046299044;14316.427342906376;14459.493917712829;14469.92765036168;14475.710485790436;14478.348474318682;11006.339619622848;11087;11087.390193539433;11092.334459932268;11111.72220694489;13373.264470454023;14009;15048.247587620575;17307.480775576754;17332.39336623212;6633.834154146185;6717.227518835396;6722.462203023746;6759.695813688396;6966.756163534252;872.9825403492022;884.4339622641514;900.5497251374313;957.7892863570004;966.1449617088927;26788.464230715275;27087.458250834872;27400.58899116515;27671.723282767318;28214.71785282121;881.7659149810776;944.4852555357347;949.5347279832832;963.1294297779891;1003.7590978165334;14071.85928140726;14165.787513187104;14573;14975.700485990437;15029.098254104789;375463;378930;379241;379515;380409;41561.79219103946;46773.5322646776;48179;48275;52972.735136324074;1160.96517104487;1175.4240422193063;1180.917335786521;1188.3107797477442;1192.7316353820322;27054.864725676245;27988.86005569959;28009;28407.28981775472;29120.56319155172;40838.1832363357;41124;41146.58853411547;41160.1767964633;41303.58696413117;327441;327856;328860;329545;329835;24566.754332456447;25002.249932502054;25044.749552504243;25047.499050018534;25078.749212507642;40049.59950400575;40212.7989360063;40447.59552404438;40691.389629156176;41542;34484.48273275853;35356.46965295574;35392;35395;35724;34230.486542702376;34260.65739342674;34280.314393712484;34472.310553789284;34472.655273446944;34418.31163376669;34726.65273347234;34951.12622184466;35140.64859351374;35548;30308.393832123675;31727;31827.20431989219;32142.35715285728;32364;23976.760232397453;24350.391240219134;24421.633675495235;24826.627600585645;24996.375235609627 10:59:22 INFO - PROCESS | 25660 | |2;jquery.html;337.3691197460749;352.19523389056;352.4026774617025;357.88547664747273;361.81728227245236;12330.630081097559;16584.50246492604;17238.999999999985;17968.910155449237;19185.904070479664;15983.440579579717;16006;16064.116473593976;16074.276657550408;17057.744133837972;4883.2430973199125;4922.55696987271;4922.585156328047;4970.776315065813;4985.40175178979;3640.6541378568995;4910.06708725341;4996.5503104720565;5000.19996800512;5541.390447050821;5850.063989761639;5910.231669882909;5911.438413350725;5928.081147422156;5943.3462319144855;5733.455321744448;5827.766889324422;5852.180694702745;5853.268341457275;5905.763769449217;5087.56755675768;5095.726068482879;5098.821541246039;5100.056489549445;5108.872278193075;18647;20892.686609700868;21098.89450552753;21278.255261065944;22170;1796.8203179682057;1866.337450205171;1872.9438116856516;1881.4543782303115;1899.8860068395802;12266.509339626404;13340.666483337896;13764.242966636832;13931.885449164043;14149.151050936976;1705.3860610180325;1742.7821522309712;1789.597340598368;1810;1840.5214644192472;66331.66834165847;70046;70614.2938570618;70869;73576.26423735748;9030.413023153475;9393.295502837178;9907.554160062815;9919.107280344804;9919.900800992042;12973.935130324287;12993.740125197442;13020.348982551024;13082.345882705828;13138.343082845819;7089.751858684921;7177.425805935617;7262.854742905112;7398;7419.777406677808;6751.797446076625;6779.525433219627;6885.655717214119;7012.088428504262;7158.2125966143685;6095.793902207462;6177.444030037318;6217.352401613485;6230.570077669622;6249.187605611241;1617.5066604685685;1664.4840099569171;1671.548681855892;1673.790776152981;1685.8229885862054;812.7155495576619;835.9623816928131;855.5593869157389;872.5506364222431;932.8087742012871;17053.06208158563;18118;18610.534736631693;18615.44153675392;18930.81069189318;854.9658013679383;863.8833757442791;875.3566128895256;892.1791951404723;894.1505569708803;9292.256619470427;10665;10691.30506517074;10737.907660762612;10769.83845242322;355049;357513;363401;364039;366819;75926;76336;76360;77102.61448692721;78993;886.9911300886909;899.7705585075764;919.3426699909596;920.3833431600709;924.9630014799333;16303.918480407521;16509.33962641529;16854.41009564659;17063.74179627836;17084.829151708323;38558;38675;38931.610683892795;39236;41240.793796030826;48848.51151488582;49213.26180107373;50777.49222507728;55390.44609553853;58431.4156858426;7479.962600186965;7484.251574842472;7632.3512501438;7894.565798881116;8042.9597852010365;8981.7754556134;9099.636014559343;10638.255322127687;11966.401679916144;12409.689757756129;10436.37381757097;10451.831210191203;10611;10691.09125724291;10756.946215268874;4552.385427967182;7426.443016773872;9307.488088155216;10592.152627789912;10856.728581785519;10634.627788027381;10792.892071079188;10882.564697412015;10970.506327215135;10974.122070234202;7413.2957369049855;7585.469017168745;7592.112374369618;7653.885191722017;7845.137034926268;9437.083610301648;10600.1519878408;10829.079528240189;10888.29226100301;10984.560617575207 10:59:22 INFO - PROCESS | 25660 | |3;mootools.html;1214.872438393968;1291.6964513339365;1355.430719097979;1389.5275606293858;1659.4855594765625;7048.823779405511;7303.087114110736;7373.520721153128;7601;7601.505902116359;7399.667014984325;7471.663775130119;7479.62601869907;7498.42533068055;7505.249475052492;1830.469163942455;2113.17586141405;2139.1122684086085;2146.838987075968;2157.492989147549;2160.5354848707525;2181.5745929543746;2181.683655869898;2183.170395249811;2186.3331683836423;2078.896055197242;2091.1635345861623;2106.083853523722;2171.913123475067;2176.1295481807324;2026;2073.2018173003353;2076.678114892194;2079.324219628627;2102.9684554731693;2144.8605840620467;2144.8605840620467;2154.4183070570857;2160.2115227941845;2161.6757486377014;7720.459567830254;7761.0298712660915;7776.261255180749;7777.144514103444;7783.8443231135625;4407.889802754925;4540.296254080623;4543.840965566223;4551.135284295973;4556.225441674923;2777.5555911054335;2932.88268469261;2935.7064293570684;2948.3661012882335;2952.542355934812;907.5371560504125;933.3326671429892;950.0406234579305;961.1877963121151;961.3078583419926;10598.576056957712;10603.78792424155;10654.467276636215;10673.999999999922;10697.946510267477;3720.516332876709;3878.204967981557;3974.5627980922077;3994.6404823566018;4003.259397011504;8434.957825210835;8435.198656127657;8442.915570844212;8443.240108390275;8460.450070745383;4422.557744225552;4434.401355816924;4439.42287502629;4440.755758433253;4459.1304695584695;3643.562772467321;3935.7835319057153;4021.9195616087513;4060.2894493463527;4108.219438306712;3266.281418088017;3318.950215746766;3396.694297513186;3401.778884372508;3425.760196786201;1002.2132625888643;1033.8811036730722;1040.968770936858;1058.3438268273567;1064.595453727594;738.8891666249998;801.4349883332237;805.3436449293807;805.8428606421692;807.8828569857455;4848.7088926299775;4924.9507504925205;4980.551750342486;5000.224965130411;5057.468965758616;398.1737893870231;400.97393669411395;414.9107941792529;419.6391103650858;421.4079218697735;3268.346330733863;3277.7869438486423;3290.670932906738;3345.347657206821;3495.3184129094584;9214.815703686023;9582.185514231369;9618.615255389706;9640.566174522164;9696.757581060388;9513.619455221851;9573.28200384974;9581.329306948586;9583.616655333848;9585.90414095864;7114.395276401564;7181.856362872713;7197.424206063502;7208.92791072093;7211.71153153868;4735.039491946833;4921.237208232729;5004.874878128076;5219.791208351623;5306.416294207557;8691.826163476568;8838.867416988622;8844.601992910206;8847.51338676379;8859.557022148738;8415.873761893454;8795.560221988748;8812.383133180616;8881.955590222266;8884.37809353339;5954.19618351517;6433.517486188461;6512.05575191604;6568.507361947777;6642.701078451385;7255.854882902207;7353.191148973716;7371.926280737124;7378.557286562824;7383.741569045058;6583.111279977142;6676.499262555425;6688.163979502562;6689.063531105747;6845.281245469353;6521.282658907611;6535.281119076993;6568.113304703804;6673.699683514156;6722.899156512558;6010.978133717366;6084.695765211633;6092.543059270661;6093.939060609515;6094.3600921904335;4206.390073439273;4385.912281754283;4414.7792610368715;4421.2262854001065;4507.414036175276;4176.164767046664;4293.50624678166;4297.699161058696;4460.197164510289;4470.798814053441 10:59:22 INFO - PROCESS | 25660 | |4;prototype.html;296.1131411422789;297.3769951950663;301.1312363830349;305.4974566837551;306.03446127467834;4735.810567577297;4743.644226683;4748.596369308613;4749.8812529686775;4806.615470762339;4689.413823272091;4693.694909830859;4703.506131856148;4709.764511774415;4711.293306004101;3534.4344904815234;3591.533100696906;3736.178040831013;3736.6823819975334;3756.1548651553453;3741.4013757798757;3746.6253374662588;3760.492333534965;3761.4169803680475;3769.8869033929027;3725.4039353703415;3733.831977561016;3740.1397678533954;3740.1397678533954;3763.604821493745;3567.3935430976794;3595.7303202259936;3693.316736403774;3710.9072273193115;3718.2377612589626;3273.770836041454;3282;3296.2748195396975;3296.686814752619;3299.472084466498;4609.101225261075;4610.723356598614;4621.537846215351;4622.214223581999;4631.9619367478535;4158.688098392633;4164.354525048592;4203.327467605169;4215.473065866767;4224.619784219435;4898.53463920927;4927.285543596158;4938.037082768861;4940.950590494085;4954.133026720309;3618.294432585647;3628.618995005539;3636.636336366342;3640.581333146669;3642.4900513928073;4947.406311242674;5149.562287205555;5170.302009228782;5170.9741451292875;5178.300929374562;3674.52231209941;3689.151495156129;3697.7411581189594;3701.8149092545264;3708.2744183025457;3733.2160246348026;3733.4773131761576;3741.4013757799166;3742.476053352478;3745.250949810049;4220.388043733641;4220.522817014082;4220.53574106848;4241.512226093977;4246.171996460661;4787.952120478751;4904.5340692634145;4930.2358134488495;4943.505649435099;4954.405471343462;1285.1132718424258;1299.2399446323818;1301.225770666452;1309.862464441239;1317.5059352742721;824.2993455562723;844.0841686769924;853.1638993786124;856.2379482260793;857.8455877941906;4240.978795106066;4347.261095561737;4409.853438106052;4429.446319210098;4432.847459660511;5026.773795179226;5076.527806935947;5098.388193416814;5102.856800143948;5116.130257856099;4418.6023257906945;4421.159979603929;4425.800838962255;4438.467383913951;4446.688731788744;2519.5590771614893;2683.07433935292;2688.8252263602803;2695.9326016849736;2704.4050308932005;4784.354112194878;4797.95202047982;4799.616030717534;4799.736014519235;4808.374911261583;4920.481855473354;5138.665986710852;5158.896822063538;5172.974135129375;5173.715445650525;4780.689255198401;4791.856244312677;4798.8962538616315;4821.614270858253;4823.204171311694;5036.194208926554;5084.5932325414615;5094.541491265803;5097.1844504879045;5106.8254301510115;4751.7861696224445;5001.674891132065;5005.449400565862;5015.22264049073;5043.5712964398435;4815.651617547092;4842.709437433765;4850;4857.781399837087;4859.586935110414;3644.8359823807464;3669.064388580895;3690.2250527389547;3698.500702405141;3710.2394009227505;5036.974815125901;5159.613029022913;5170.6380553360905;5172.5603323716405;5173.628988318023;4973.041040580849;4978.506448065494;4990.950090499194;4991.926735751831;5011.348524691913;4971.751412429437;4991.401031876053;4994.300797888372;4998.400191976986;5008.69947803133;4731.29030645406;4912.459629440687;4932.1122198004705;4937.28409380645;4942.838432968178;4798.880027999328;4884.755762211804;4944.258361245842;4990.251462280687;5004.174311238605;4856.514348565115;4859.854204373874;4869.95130048695;4875.341828853201;4876.561109500162 10:59:22 INFO - PROCESS | 25660 | |5;yui.html;593.2439977666108;672.9427998620117;684.4182444921814;691.9792406227812;692.6121372031663;11897.40512974351;13077.476900923975;13091.14907531011;13109.80335294969;13127.540536081238;12980.610581682564;13083.542076027341;13106.14810037347;13124.93437532811;13199;592.0408937521222;711.214108410207;724.1527412926888;737.4248086492545;737.4358615659025;744.709563270325;745.2956955676888;746.9215732348107;746.9589172595505;747.3087394160407;777.8172129549552;778.3500776851336;779.1507257089771;779.329776392305;779.4582764978343;624.8625302433458;640.3916279534461;641.2497378067626;644.4296797334346;774.9651265693029;550.2104480071071;565.0761005755578;568.8122919436576;569.1491220625151;569.2087997683224;11789.934616999642;11817.527298908033;11819.763604727943;11826.11304152192;11836.585719499777;11610.883891161066;11873;12068.758624827542;12412.689682758004;12834.871651283462;11289.943550282278;11585.36280504572;11631.941840290829;11807.881921180764;11940.820887686692;3874.8062596870327;3913.3151698452657;3989.920201595981;4094.996725802168;4107.404426358191;61195.69402153005;61758.764824703256;62369.12892613229;63839;65487.672561637366;8837.823243535093;8945.776355591031;9015;9068.229200517899;9124.133207345292;16012.439564615415;16032.11823349727;16057.518274451784;16065.919670401807;16830;6749.42629876466;6750.32496750321;6757.8648427031185;6849.623270720059;7078.115235595551;3505.158761897126;3573.0352804743093;3711.16498787776;3826.0626146593704;3861.8455261789777;2956.541736030919;3052.0538633023834;3080.753539716817;3083.629964404241;3089.4902341113916;502.60043265603935;504.3065784546249;509.9796008159632;510.62979339978216;516.2901011109724;532.4595535531439;550.5540512185063;552.9281193444907;559.2478116932718;565.172022986327;7503.737369192052;7607.429442791813;7993.360531157494;8023.1575684553445;8338.499690018618;520.5965376832985;544.3196004993757;557.83543854563;561.3236050559067;569.9886002279932;3630.5824830144343;3660.890173294805;3690.464882592009;3710.461983012448;3789.791561464146;95109.5244523773;100207;101605;103323.4833825826;103394;51523.48476515262;61371.38628613746;91227;92148;92252.53873730589;79439;79837.60081199557;80919;81326;82337.58831205925;7149.9285007149265;7336.523125996793;7360.227176146427;7570;7681.500702454323;43241.783791080845;44690.776546117064;44761;44850.102997939204;45234.77382613066;48558.5144148554;61626.69186654038;62317;62897;63456.682716586125;8883.73348799537;9372.39079459833;9626.133647971716;9808.705738827846;9889.456079915673;10578.471076446302;10723.571057157626;10882.782344353227;11465.025472834903;11501.597444089417;9694.660686875926;10238.232132589937;10382.948085259526;10430.113440357654;10718.946405267923;10113.797724045626;10127.139193168663;10323.122534584343;10332.896671033193;11184.71591759359;11040.558377665126;11156.776864462827;11171.66485005451;11207.159463040467;11985.161038727205;8615.82768344617;8781;9055.411398259092;9064.728058158265;9160.129787670161;8055;8274.213949674771;8937.28501719848;9283.535823208947;9898.802023959337 10:59:22 INFO - PROCESS | 25660 | __end_tp_report 10:59:22 INFO - PROCESS | 25660 | __start_cc_report 10:59:22 INFO - PROCESS | 25660 | _x_x_mozilla_cycle_collect,1030 10:59:22 INFO - PROCESS | 25660 | __end_cc_report 10:59:22 INFO - PROCESS | 25660 | __startTimestamp1472579962125__endTimestamp 10:59:22 INFO - PROCESS | 25660 | 10:59:22 INFO - PROCESS | 25660 | ------- Summary: start ------- 10:59:22 INFO - PROCESS | 25660 | Number of tests: 6 10:59:22 INFO - PROCESS | 25660 | 10:59:22 INFO - PROCESS | 25660 | [#0] dojo.html Cycles:165 Average:4648.92 Median:4774.21 stddev:473.55 (9.9%) stddev-sans-first:446.81 10:59:22 INFO - PROCESS | 25660 | Values: 2597.2 2615.5 2636.8 2670.9 2690.1 4461.8 4607.0 4628.4 4723.0 4788.2 4640.9 4675.2 4699.3 4699.9 4723.4 4231.6 4240.5 4306.4 4369.6 4487.3 4370.3 4431.5 4455.1 4455.4 4458.8 4203.4 4260.6 4368.8 4387.5 4481.7 4306.8 4314.3 4316.7 4317.5 4320.0 4212.8 4218.2 4220.1 4248.1 4258.2 4583.7 4675.8 4689.4 4690.8 4697.3 4777.5 5019.4 5020.8 5028.5 5108.8 5026.4 5069.6 5098.5 5112.8 5116.6 4755.1 4758.8 4773.3 4776.1 4832.1 4958.6 5019.4 5201.9 5208.9 5214.2 4843.4 4852.7 4883.4 4895.7 4977.4 4991.7 4995.5 5007.2 5051.0 5051.8 4931.4 4940.2 4943.8 4963.3 4990.3 4843.1 4904.1 4918.7 4975.8 5002.5 4344.0 4350.2 4390.3 4401.9 4403.9 3790.0 3796.8 3797.9 3828.2 3840.5 4239.4 4333.0 4367.1 4377.5 4418.4 4978.9 5081.3 5088.3 5098.5 5100.6 4360.0 4361.2 4369.7 4411.1 4434.0 4544.2 4565.0 4578.0 4578.1 4659.8 4608.5 4706.1 4735.1 4755.9 4768.9 5071.3 5077.0 5079.7 5121.1 5134.4 4768.8 4775.0 4778.7 4780.2 4834.7 4758.9 4880.8 4943.3 4981.7 5056.6 5022.2 5022.5 5026.1 5028.2 5057.7 4866.8 4893.4 4898.1 4903.5 4945.2 4735.0 4791.7 4837.0 4908.7 4957.8 4779.8 4785.1 4786.1 4829.5 4839.2 4902.7 4912.8 4914.9 4919.9 4968.5 4650.0 4681.0 4774.2 4818.0 4836.3 10:59:22 INFO - PROCESS | 25660 | 10:59:22 INFO - PROCESS | 25660 | [#1] ext.html Cycles:180 Average:46096.73 Median:34478.57 stddev:84690.09 (245.6%) stddev-sans-first:84858.69 10:59:22 INFO - PROCESS | 25660 | Values: 571.1 573.4 577.7 584.5 585.1 27854.2 30504.4 30574.2 37919.6 39541.4 39242.2 39662.6 39673.2 39689.6 39779.6 7573.3 7679.8 7721.5 7725.1 7733.8 7337.1 7520.4 7529.2 7629.2 7710.0 9728.4 9991.6 9995.7 10274.5 10303.4 9822.6 9875.8 9904.9 9909.5 9914.8 9759.8 9810.7 9822.5 9885.5 9902.9 37005.8 37223.4 37491.6 37502.2 37508.0 2966.7 3030.8 3200.4 3241.0 3586.3 27573.0 27654.2 27667.3 28444.9 29235.9 3132.0 3181.2 3186.7 3207.9 3221.3 241029.0 241083.0 243449.0 243942.0 245259.0 49711.0 50002.2 50075.2 50099.2 50100.7 31693.4 32462.4 32557.7 34071.3 34078.1 14316.4 14459.5 14469.9 14475.7 14478.3 11006.3 11087.0 11087.4 11092.3 11111.7 13373.3 14009.0 15048.2 17307.5 17332.4 6633.8 6717.2 6722.5 6759.7 6966.8 873.0 884.4 900.5 957.8 966.1 26788.5 27087.5 27400.6 27671.7 28214.7 881.8 944.5 949.5 963.1 1003.8 14071.9 14165.8 14573.0 14975.7 15029.1 375463.0 378930.0 379241.0 379515.0 380409.0 41561.8 46773.5 48179.0 48275.0 52972.7 1161.0 1175.4 1180.9 1188.3 1192.7 27054.9 27988.9 28009.0 28407.3 29120.6 40838.2 41124.0 41146.6 41160.2 41303.6 327441.0 327856.0 328860.0 329545.0 329835.0 24566.8 25002.2 25044.7 25047.5 25078.7 40049.6 40212.8 40447.6 40691.4 41542.0 34484.5 35356.5 35392.0 35395.0 35724.0 34230.5 34260.7 34280.3 34472.3 34472.7 34418.3 34726.7 34951.1 35140.6 35548.0 30308.4 31727.0 31827.2 32142.4 32364.0 23976.8 24350.4 24421.6 24826.6 24996.4 10:59:22 INFO - PROCESS | 25660 | 10:59:22 INFO - PROCESS | 25660 | [#2] jquery.html Cycles:180 Average:23821.08 Median:26865.88 stddev:59902.50 (223.0%) stddev-sans-first:60044.59 10:59:22 INFO - PROCESS | 25660 | Values: 337.4 352.2 352.4 357.9 361.8 12330.6 16584.5 17239.0 17968.9 19185.9 15983.4 16006.0 16064.1 16074.3 17057.7 4883.2 4922.6 4922.6 4970.8 4985.4 3640.7 4910.1 4996.6 5000.2 5541.4 5850.1 5910.2 5911.4 5928.1 5943.3 5733.5 5827.8 5852.2 5853.3 5905.8 5087.6 5095.7 5098.8 5100.1 5108.9 18647.0 20892.7 21098.9 21278.3 22170.0 1796.8 1866.3 1872.9 1881.5 1899.9 12266.5 13340.7 13764.2 13931.9 14149.2 1705.4 1742.8 1789.6 1810.0 1840.5 66331.7 70046.0 70614.3 70869.0 73576.3 9030.4 9393.3 9907.6 9919.1 9919.9 12973.9 12993.7 13020.3 13082.3 13138.3 7089.8 7177.4 7262.9 7398.0 7419.8 6751.8 6779.5 6885.7 7012.1 7158.2 6095.8 6177.4 6217.4 6230.6 6249.2 1617.5 1664.5 1671.5 1673.8 1685.8 812.7 836.0 855.6 872.6 932.8 17053.1 18118.0 18610.5 18615.4 18930.8 855.0 863.9 875.4 892.2 894.2 9292.3 10665.0 10691.3 10737.9 10769.8 355049.0 357513.0 363401.0 364039.0 366819.0 75926.0 76336.0 76360.0 77102.6 78993.0 887.0 899.8 919.3 920.4 925.0 16303.9 16509.3 16854.4 17063.7 17084.8 38558.0 38675.0 38931.6 39236.0 41240.8 48848.5 49213.3 50777.5 55390.4 58431.4 7480.0 7484.3 7632.4 7894.6 8043.0 8981.8 9099.6 10638.3 11966.4 12409.7 10436.4 10451.8 10611.0 10691.1 10756.9 4552.4 7426.4 9307.5 10592.2 10856.7 10634.6 10792.9 10882.6 10970.5 10974.1 7413.3 7585.5 7592.1 7653.9 7845.1 9437.1 10600.2 10829.1 10888.3 10984.6 10:59:22 INFO - PROCESS | 25660 | 10:59:22 INFO - PROCESS | 25660 | [#3] mootools.html Cycles:180 Average:4974.06 Median:4791.87 stddev:2853.86 (59.6%) stddev-sans-first:2847.88 10:59:22 INFO - PROCESS | 25660 | Values: 1214.9 1291.7 1355.4 1389.5 1659.5 7048.8 7303.1 7373.5 7601.0 7601.5 7399.7 7471.7 7479.6 7498.4 7505.2 1830.5 2113.2 2139.1 2146.8 2157.5 2160.5 2181.6 2181.7 2183.2 2186.3 2078.9 2091.2 2106.1 2171.9 2176.1 2026.0 2073.2 2076.7 2079.3 2103.0 2144.9 2144.9 2154.4 2160.2 2161.7 7720.5 7761.0 7776.3 7777.1 7783.8 4407.9 4540.3 4543.8 4551.1 4556.2 2777.6 2932.9 2935.7 2948.4 2952.5 907.5 933.3 950.0 961.2 961.3 10598.6 10603.8 10654.5 10674.0 10697.9 3720.5 3878.2 3974.6 3994.6 4003.3 8435.0 8435.2 8442.9 8443.2 8460.5 4422.6 4434.4 4439.4 4440.8 4459.1 3643.6 3935.8 4021.9 4060.3 4108.2 3266.3 3319.0 3396.7 3401.8 3425.8 1002.2 1033.9 1041.0 1058.3 1064.6 738.9 801.4 805.3 805.8 807.9 4848.7 4925.0 4980.6 5000.2 5057.5 398.2 401.0 414.9 419.6 421.4 3268.3 3277.8 3290.7 3345.3 3495.3 9214.8 9582.2 9618.6 9640.6 9696.8 9513.6 9573.3 9581.3 9583.6 9585.9 7114.4 7181.9 7197.4 7208.9 7211.7 4735.0 4921.2 5004.9 5219.8 5306.4 8691.8 8838.9 8844.6 8847.5 8859.6 8415.9 8795.6 8812.4 8882.0 8884.4 5954.2 6433.5 6512.1 6568.5 6642.7 7255.9 7353.2 7371.9 7378.6 7383.7 6583.1 6676.5 6688.2 6689.1 6845.3 6521.3 6535.3 6568.1 6673.7 6722.9 6011.0 6084.7 6092.5 6093.9 6094.4 4206.4 4385.9 4414.8 4421.2 4507.4 4176.2 4293.5 4297.7 4460.2 4470.8 10:59:22 INFO - PROCESS | 25660 | 10:59:22 INFO - PROCESS | 25660 | [#4] prototype.html Cycles:180 Average:4131.50 Median:4733.55 stddev:1186.51 (25.1%) stddev-sans-first:1154.39 10:59:22 INFO - PROCESS | 25660 | Values: 296.1 297.4 301.1 305.5 306.0 4735.8 4743.6 4748.6 4749.9 4806.6 4689.4 4693.7 4703.5 4709.8 4711.3 3534.4 3591.5 3736.2 3736.7 3756.2 3741.4 3746.6 3760.5 3761.4 3769.9 3725.4 3733.8 3740.1 3740.1 3763.6 3567.4 3595.7 3693.3 3710.9 3718.2 3273.8 3282.0 3296.3 3296.7 3299.5 4609.1 4610.7 4621.5 4622.2 4632.0 4158.7 4164.4 4203.3 4215.5 4224.6 4898.5 4927.3 4938.0 4941.0 4954.1 3618.3 3628.6 3636.6 3640.6 3642.5 4947.4 5149.6 5170.3 5171.0 5178.3 3674.5 3689.2 3697.7 3701.8 3708.3 3733.2 3733.5 3741.4 3742.5 3745.3 4220.4 4220.5 4220.5 4241.5 4246.2 4788.0 4904.5 4930.2 4943.5 4954.4 1285.1 1299.2 1301.2 1309.9 1317.5 824.3 844.1 853.2 856.2 857.8 4241.0 4347.3 4409.9 4429.4 4432.8 5026.8 5076.5 5098.4 5102.9 5116.1 4418.6 4421.2 4425.8 4438.5 4446.7 2519.6 2683.1 2688.8 2695.9 2704.4 4784.4 4798.0 4799.6 4799.7 4808.4 4920.5 5138.7 5158.9 5173.0 5173.7 4780.7 4791.9 4798.9 4821.6 4823.2 5036.2 5084.6 5094.5 5097.2 5106.8 4751.8 5001.7 5005.4 5015.2 5043.6 4815.7 4842.7 4850.0 4857.8 4859.6 3644.8 3669.1 3690.2 3698.5 3710.2 5037.0 5159.6 5170.6 5172.6 5173.6 4973.0 4978.5 4991.0 4991.9 5011.3 4971.8 4991.4 4994.3 4998.4 5008.7 4731.3 4912.5 4932.1 4937.3 4942.8 4798.9 4884.8 4944.3 4990.3 5004.2 4856.5 4859.9 4870.0 4875.3 4876.6 10:59:22 INFO - PROCESS | 25660 | 10:59:22 INFO - PROCESS | 25660 | [#5] yui.html Cycles:180 Average:17387.92 Median:555.38 stddev:25749.96 (4636.4%) stddev-sans-first:25791.32 10:59:22 INFO - PROCESS | 25660 | Values: 593.2 672.9 684.4 692.0 692.6 11897.4 13077.5 13091.1 13109.8 13127.5 12980.6 13083.5 13106.1 13124.9 13199.0 592.0 711.2 724.2 737.4 737.4 744.7 745.3 746.9 747.0 747.3 777.8 778.4 779.2 779.3 779.5 624.9 640.4 641.2 644.4 775.0 550.2 565.1 568.8 569.1 569.2 11789.9 11817.5 11819.8 11826.1 11836.6 11610.9 11873.0 12068.8 12412.7 12834.9 11289.9 11585.4 11631.9 11807.9 11940.8 3874.8 3913.3 3989.9 4095.0 4107.4 61195.7 61758.8 62369.1 63839.0 65487.7 8837.8 8945.8 9015.0 9068.2 9124.1 16012.4 16032.1 16057.5 16065.9 16830.0 6749.4 6750.3 6757.9 6849.6 7078.1 3505.2 3573.0 3711.2 3826.1 3861.8 2956.5 3052.1 3080.8 3083.6 3089.5 502.6 504.3 510.0 510.6 516.3 532.5 550.6 552.9 559.2 565.2 7503.7 7607.4 7993.4 8023.2 8338.5 520.6 544.3 557.8 561.3 570.0 3630.6 3660.9 3690.5 3710.5 3789.8 95109.5 100207.0 101605.0 103323.5 103394.0 51523.5 61371.4 91227.0 92148.0 92252.5 79439.0 79837.6 80919.0 81326.0 82337.6 7149.9 7336.5 7360.2 7570.0 7681.5 43241.8 44690.8 44761.0 44850.1 45234.8 48558.5 61626.7 62317.0 62897.0 63456.7 8883.7 9372.4 9626.1 9808.7 9889.5 10578.5 10723.6 10882.8 11465.0 11501.6 9694.7 10238.2 10382.9 10430.1 10718.9 10113.8 10127.1 10323.1 10332.9 11184.7 11040.6 11156.8 11171.7 11207.2 11985.2 8615.8 8781.0 9055.4 9064.7 9160.1 8055.0 8274.2 8937.3 9283.5 9898.8 10:59:22 INFO - PROCESS | 25660 | -------- Summary: end -------- 10:59:22 INFO - PROCESS | 25660 | 10:59:23 INFO - TEST-INFO | 25660: exit 0 10:59:23 INFO - TEST-OK | dromaeo_css | took 1121709ms 10:59:23 INFO - TEST-START | kraken 10:59:23 INFO - operating with platform_type : linux_ 10:59:23 INFO - Initialising browser for kraken test... 10:59:23 INFO - TEST-INFO | started process 25963 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkSIasi/profile http://localhost:45087/getInfo.html) 10:59:27 INFO - PROCESS | 25963 | __metrics Screen width/height:1600/1200 10:59:27 INFO - PROCESS | 25963 | colorDepth:24 10:59:27 INFO - PROCESS | 25963 | Browser inner width/height: 1024/697 10:59:27 INFO - PROCESS | 25963 | __metrics 10:59:28 INFO - PROCESS | 25963 | 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" 10:59:28 INFO - PROCESS | 25963 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:59:28 INFO - PROCESS | 25963 | console.error: PushService: 10:59:28 INFO - PROCESS | 25963 | stateChangeProcessEnqueue: Error transitioning state 10:59:28 INFO - PROCESS | 25963 | UnknownError 10:59:29 INFO - TEST-INFO | 25963: exit 0 10:59:29 INFO - Browser initialized. 10:59:29 INFO - Running cycle 1/1 for kraken test... 10:59:29 INFO - TEST-INFO | started process 26045 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkSIasi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:59:42 INFO - PROCESS | 26045 | RSS: Main: 192970752 10:59:42 INFO - PROCESS | 26045 | 10:59:44 INFO - PROCESS | 26045 | Cycle 1(1): loaded http://localhost:45087/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45087/tests/kraken/driver.html?testName=audio-beat-detection) 10:59:45 INFO - PROCESS | 26045 | RSS: Main: 237424640 10:59:45 INFO - PROCESS | 26045 | 10:59:48 INFO - PROCESS | 26045 | Cycle 1(1): loaded http://localhost:45087/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45087/tests/kraken/driver.html?testName=audio-dft) 10:59:49 INFO - PROCESS | 26045 | RSS: Main: 228421632 10:59:49 INFO - PROCESS | 26045 | 10:59:52 INFO - PROCESS | 26045 | Cycle 1(1): loaded http://localhost:45087/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45087/tests/kraken/driver.html?testName=audio-fft) 10:59:52 INFO - PROCESS | 26045 | RSS: Main: 307548160 10:59:52 INFO - PROCESS | 26045 | 10:59:55 INFO - PROCESS | 26045 | Cycle 1(1): loaded http://localhost:45087/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45087/tests/kraken/driver.html?testName=audio-oscillator) 10:59:55 INFO - PROCESS | 26045 | RSS: Main: 216498176 10:59:55 INFO - PROCESS | 26045 | 10:59:57 INFO - PROCESS | 26045 | Cycle 1(1): loaded http://localhost:45087/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45087/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:59:58 INFO - PROCESS | 26045 | RSS: Main: 218370048 10:59:58 INFO - PROCESS | 26045 | 11:00:01 INFO - PROCESS | 26045 | Cycle 1(1): loaded http://localhost:45087/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45087/tests/kraken/driver.html?testName=imaging-darkroom) 11:00:02 INFO - PROCESS | 26045 | RSS: Main: 257691648 11:00:02 INFO - PROCESS | 26045 | 11:00:05 INFO - PROCESS | 26045 | Cycle 1(1): loaded http://localhost:45087/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45087/tests/kraken/driver.html?testName=imaging-desaturate) 11:00:06 INFO - PROCESS | 26045 | RSS: Main: 251940864 11:00:06 INFO - PROCESS | 26045 | 11:00:09 INFO - PROCESS | 26045 | Cycle 1(1): loaded http://localhost:45087/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45087/tests/kraken/driver.html?testName=json-parse-financial) 11:00:09 INFO - PROCESS | 26045 | RSS: Main: 254754816 11:00:09 INFO - PROCESS | 26045 | 11:00:11 INFO - PROCESS | 26045 | Cycle 1(1): loaded http://localhost:45087/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45087/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:00:12 INFO - PROCESS | 26045 | RSS: Main: 218652672 11:00:12 INFO - PROCESS | 26045 | 11:00:14 INFO - PROCESS | 26045 | Cycle 1(1): loaded http://localhost:45087/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45087/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:00:14 INFO - PROCESS | 26045 | RSS: Main: 226533376 11:00:14 INFO - PROCESS | 26045 | 11:00:16 INFO - PROCESS | 26045 | Cycle 1(1): loaded http://localhost:45087/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45087/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:00:17 INFO - PROCESS | 26045 | RSS: Main: 224669696 11:00:17 INFO - PROCESS | 26045 | 11:00:20 INFO - PROCESS | 26045 | Cycle 1(1): loaded http://localhost:45087/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45087/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:00:21 INFO - PROCESS | 26045 | RSS: Main: 219848704 11:00:21 INFO - PROCESS | 26045 | 11:00:24 INFO - PROCESS | 26045 | Cycle 1(1): loaded http://localhost:45087/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45087/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:00:24 INFO - PROCESS | 26045 | RSS: Main: 223797248 11:00:24 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | Cycle 1(1): loaded http://localhost:45087/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45087/tests/kraken/driver.html?testName=ai-astar) 11:00:26 INFO - PROCESS | 26045 | RSS: Main: 274309120 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | __start_tp_report 11:00:26 INFO - PROCESS | 26045 | _x_x_mozilla_page_load 11:00:26 INFO - PROCESS | 26045 | _x_x_mozilla_page_load_details 11:00:26 INFO - PROCESS | 26045 | |i|pagename|runs| 11:00:26 INFO - PROCESS | 26045 | |0;ai-astar;117;114;113;114;116;115;114;105;114;115 11:00:26 INFO - PROCESS | 26045 | |1;audio-beat-detection;165;121;153;190;124;147;148;122;185;128 11:00:26 INFO - PROCESS | 26045 | |2;audio-dft;162;162;161;163;162;163;162;161;161;161 11:00:26 INFO - PROCESS | 26045 | |3;audio-fft;75;75;74;180;78;76;75;77;162;76 11:00:26 INFO - PROCESS | 26045 | |4;audio-oscillator;83;85;159;84;83;84;139;83;83;139 11:00:26 INFO - PROCESS | 26045 | |5;imaging-gaussian-blur;117;117;118;118;118;117;117;117;119;117 11:00:26 INFO - PROCESS | 26045 | |6;imaging-darkroom;146;146;146;145;147;146;146;146;146;147 11:00:26 INFO - PROCESS | 26045 | |7;imaging-desaturate;96;95;96;96;95;95;96;95;96;96 11:00:26 INFO - PROCESS | 26045 | |8;json-parse-financial;65;65;64;63;64;67;64;77;63;63 11:00:26 INFO - PROCESS | 26045 | |9;json-stringify-tinderbox;65;65;65;64;65;65;66;65;65;66 11:00:26 INFO - PROCESS | 26045 | |10;stanford-crypto-aes;82;82;81;83;85;82;87;88;82;82 11:00:26 INFO - PROCESS | 26045 | |11;stanford-crypto-ccm;137;132;132;133;130;142;141;130;187;136 11:00:26 INFO - PROCESS | 26045 | |12;stanford-crypto-pbkdf2;173;173;174;175;172;175;180;174;179;183 11:00:26 INFO - PROCESS | 26045 | |13;stanford-crypto-sha256-iterative;70;68;69;69;71;68;68;68;68;71 11:00:26 INFO - PROCESS | 26045 | __end_tp_report 11:00:26 INFO - PROCESS | 26045 | __start_cc_report 11:00:26 INFO - PROCESS | 26045 | _x_x_mozilla_cycle_collect,1954 11:00:26 INFO - PROCESS | 26045 | __end_cc_report 11:00:26 INFO - PROCESS | 26045 | __startTimestamp1472580026692__endTimestamp 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | ------- Summary: start ------- 11:00:26 INFO - PROCESS | 26045 | Number of tests: 14 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.50 stddev:3.27 (2.9%) stddev-sans-first:3.24 11:00:26 INFO - PROCESS | 26045 | Values: 117.0 114.0 113.0 114.0 116.0 115.0 114.0 105.0 114.0 115.0 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | [#1] audio-beat-detection Cycles:10 Average:148.30 Median:150.50 stddev:25.45 (16.9%) stddev-sans-first:26.26 11:00:26 INFO - PROCESS | 26045 | Values: 165.0 121.0 153.0 190.0 124.0 147.0 148.0 122.0 185.0 128.0 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 11:00:26 INFO - PROCESS | 26045 | Values: 162.0 162.0 161.0 163.0 162.0 163.0 162.0 161.0 161.0 161.0 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | [#3] audio-fft Cycles:10 Average:94.80 Median:75.50 stddev:40.40 (53.5%) stddev-sans-first:42.21 11:00:26 INFO - PROCESS | 26045 | Values: 75.0 75.0 74.0 180.0 78.0 76.0 75.0 77.0 162.0 76.0 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | [#4] audio-oscillator Cycles:10 Average:102.20 Median:83.00 stddev:30.49 (36.7%) stddev-sans-first:31.54 11:00:26 INFO - PROCESS | 26045 | Values: 83.0 85.0 159.0 84.0 83.0 84.0 139.0 83.0 83.0 139.0 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:117.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 11:00:26 INFO - PROCESS | 26045 | Values: 117.0 117.0 118.0 118.0 118.0 117.0 117.0 117.0 119.0 117.0 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 11:00:26 INFO - PROCESS | 26045 | Values: 146.0 146.0 146.0 145.0 147.0 146.0 146.0 146.0 146.0 147.0 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 11:00:26 INFO - PROCESS | 26045 | Values: 96.0 95.0 96.0 96.0 95.0 95.0 96.0 95.0 96.0 96.0 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:64.50 stddev:4.22 (6.5%) stddev-sans-first:4.48 11:00:26 INFO - PROCESS | 26045 | Values: 65.0 65.0 64.0 63.0 64.0 67.0 64.0 77.0 63.0 63.0 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 11:00:26 INFO - PROCESS | 26045 | Values: 65.0 65.0 65.0 64.0 65.0 65.0 66.0 65.0 65.0 66.0 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:82.50 stddev:2.41 (2.9%) stddev-sans-first:2.51 11:00:26 INFO - PROCESS | 26045 | Values: 82.0 82.0 81.0 83.0 85.0 82.0 87.0 88.0 82.0 82.0 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | [#11] stanford-crypto-ccm Cycles:10 Average:140.00 Median:136.50 stddev:17.05 (12.5%) stddev-sans-first:18.05 11:00:26 INFO - PROCESS | 26045 | Values: 137.0 132.0 132.0 133.0 130.0 142.0 141.0 130.0 187.0 136.0 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:175.00 stddev:3.61 (2.1%) stddev-sans-first:3.69 11:00:26 INFO - PROCESS | 26045 | Values: 173.0 173.0 174.0 175.0 172.0 175.0 180.0 174.0 179.0 183.0 11:00:26 INFO - PROCESS | 26045 | 11:00:26 INFO - PROCESS | 26045 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.00 stddev:1.25 (1.8%) stddev-sans-first:1.27 11:00:26 INFO - PROCESS | 26045 | Values: 70.0 68.0 69.0 69.0 71.0 68.0 68.0 68.0 68.0 71.0 11:00:26 INFO - PROCESS | 26045 | -------- Summary: end -------- 11:00:26 INFO - PROCESS | 26045 | 11:00:27 INFO - TEST-INFO | 26045: exit 0 11:00:27 INFO - TEST-OK | kraken | took 64881ms 11:00:27 INFO - SUITE-END | took 1186s 11:00:28 INFO - Completed test suite (00:19:46) 11:00:28 INFO - javascript benchmark 11:00:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2597.2467984284553, 2615.4507553413787, 2636.78905687545, 2670.893164273429, 2690.1257091445286, 4461.843835465759, 4606.953930460694, 4628.421447319085, 4723.0, 4788.209945359016, 4640.883977900554, 4675.158471475142, 4699.271612899997, 4699.929501057479, 4723.385959825227, 4231.576842315759, 4240.469941257343, 4306.375575541545, 4369.584889435499, 4487.304467807495, 4370.3007518797, 4431.468223813147, 4455.086707225011, 4455.443069616298, 4458.843940462085, 4203.4115223868375, 4260.637845783112, 4368.7815609219415, 4387.495438024636, 4481.731096134226, 4306.8277268909205, 4314.331278651783, 4316.69783115179, 4317.546657600969, 4320.027993701392, 4212.7682977436225, 4218.2196293685765, 4220.092680073768, 4248.065425606362, 4258.169656916903, 4583.747893865836, 4675.7895894684825, 4689.413823272091, 4690.788914498856, 4697.271922851964, 4777.522247775195, 5019.447860735316, 5020.799168033311, 5028.4971502849785, 5108.770105345268, 5026.447090820006, 5069.569086627641, 5098.515641014098, 5112.821051263188, 5116.565091967187, 4755.072760811643, 4758.78585463655, 4773.284007398848, 4776.068666609977, 4832.130216560982, 4958.578520825698, 5019.422766381913, 5201.895962080813, 5208.869778255498, 5214.217867319857, 4843.418789745253, 4852.684575502581, 4883.438404583448, 4895.7062576245535, 4977.427595826526, 4991.700497970133, 4995.450500444948, 5007.198848184273, 5050.974745126325, 5051.82318618854, 4931.3835770528685, 4940.184869496493, 4943.802247910044, 4963.305137280783, 4990.30135780991, 4843.128236917389, 4904.092742842586, 4918.655694101379, 4975.776090075955, 5002.474740152305, 4344.044310251739, 4350.173467041315, 4390.3414487827085, 4401.867943961686, 4403.889902752393, 3790.033541446913, 3796.772193668389, 3797.886063418102, 3828.2343531293855, 3840.462335273064, 4239.40648309237, 4333.0, 4367.082912588328, 4377.474703035656, 4418.359337895987, 4978.850634480971, 5081.28861959326, 5088.313077734532, 5098.515641014098, 5100.56645185156, 4360.0, 4361.214981303396, 4369.71596846204, 4411.073674528321, 4434.024514606781, 4544.204764166324, 4564.977175114103, 4578.03861189154, 4578.1301552705545, 4659.8136074557315, 4608.539146085364, 4706.058788242297, 4735.076660051257, 4755.857324280277, 4768.928466072943, 5071.315372424676, 5077.0, 5079.746012699424, 5121.103806833864, 5134.435212126588, 4768.785404656729, 4774.95225047745, 4778.689385189951, 4780.187368147493, 4834.661573689948, 4758.857234282977, 4880.755962201947, 4943.30793688891, 4981.651284410202, 5056.62075344358, 5022.246663000433, 5022.522860328263, 5026.120428924996, 5028.220625803006, 5057.74711264428, 4866.829660961849, 4893.363862697826, 4898.118338699069, 4903.485134060872, 4945.208766597327, 4734.976325118352, 4791.6885402450125, 4836.95163048365, 4908.730019848943, 4957.82647607332, 4779.784909679003, 4785.090832741768, 4786.090642777861, 4829.492903245249, 4839.177339852163, 4902.730349830794, 4912.754362281943, 4914.9017019658695, 4919.852404427873, 4968.478309777566, 4649.953500464952, 4680.976595117003, 4774.21225497789, 4817.975910120567, 4836.250381190921], "value": 4619.572726959892, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.0976656882127, 573.4179807495393, 577.6851615869351, 584.4535359438925, 585.0580565289882, 27854.164375068754, 30504.389912201743, 30574.23564410888, 37919.62080379196, 39541.406878896836, 39242.21515569694, 39662.60337396625, 39673.20653586926, 39689.603103968955, 39779.60220397795, 7573.280538348862, 7679.8080047998965, 7721.459497835153, 7725.111612164589, 7733.767986960372, 7337.0828646419195, 7520.435967302446, 7529.209433009537, 7629.160792312841, 7710.036245469317, 9728.367656102331, 9991.600335986552, 9995.70012899614, 10274.510196021572, 10303.433311167882, 9822.557984890662, 9875.753106172331, 9904.851427228597, 9909.504524773733, 9914.752131196741, 9759.756006099833, 9810.656627018092, 9822.508874556244, 9885.505724713807, 9902.851457228147, 37005.81497092497, 37223.441648375294, 37491.62508374909, 37502.24995500102, 37508.0, 2966.688497707731, 3030.8181509109304, 3200.359928014383, 3240.96759032409, 3586.28274345132, 27573.0, 27654.170374888785, 27667.308317292227, 28444.857775711196, 29235.85382073076, 3131.9530207046914, 3181.1569933967517, 3186.713195812365, 3207.8877239296744, 3221.3074189049466, 241029.0, 241083.0, 243449.0, 243942.0, 245259.0, 49711.0, 50002.249966250536, 50075.24887126696, 50099.24851127236, 50100.74949625228, 31693.366132677216, 32462.35075298528, 32557.67442325594, 34071.31857362839, 34078.148046299044, 14316.427342906376, 14459.493917712829, 14469.92765036168, 14475.710485790436, 14478.348474318682, 11006.339619622848, 11087.0, 11087.390193539433, 11092.334459932268, 11111.72220694489, 13373.264470454023, 14009.0, 15048.247587620575, 17307.480775576754, 17332.39336623212, 6633.834154146185, 6717.227518835396, 6722.462203023746, 6759.695813688396, 6966.756163534252, 872.9825403492022, 884.4339622641514, 900.5497251374313, 957.7892863570004, 966.1449617088927, 26788.464230715275, 27087.458250834872, 27400.58899116515, 27671.723282767318, 28214.71785282121, 881.7659149810776, 944.4852555357347, 949.5347279832832, 963.1294297779891, 1003.7590978165334, 14071.85928140726, 14165.787513187104, 14573.0, 14975.700485990437, 15029.098254104789, 375463.0, 378930.0, 379241.0, 379515.0, 380409.0, 41561.79219103946, 46773.5322646776, 48179.0, 48275.0, 52972.735136324074, 1160.96517104487, 1175.4240422193063, 1180.917335786521, 1188.3107797477442, 1192.7316353820322, 27054.864725676245, 27988.86005569959, 28009.0, 28407.28981775472, 29120.56319155172, 40838.1832363357, 41124.0, 41146.58853411547, 41160.1767964633, 41303.58696413117, 327441.0, 327856.0, 328860.0, 329545.0, 329835.0, 24566.754332456447, 25002.249932502054, 25044.749552504243, 25047.499050018534, 25078.749212507642, 40049.59950400575, 40212.7989360063, 40447.59552404438, 40691.389629156176, 41542.0, 34484.48273275853, 35356.46965295574, 35392.0, 35395.0, 35724.0, 34230.486542702376, 34260.65739342674, 34280.314393712484, 34472.310553789284, 34472.655273446944, 34418.31163376669, 34726.65273347234, 34951.12622184466, 35140.64859351374, 35548.0, 30308.393832123675, 31727.0, 31827.20431989219, 32142.35715285728, 32364.0, 23976.760232397453, 24350.391240219134, 24421.633675495235, 24826.627600585645, 24996.375235609627], "value": 17182.06429528444, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.3691197460749, 352.19523389056, 352.4026774617025, 357.88547664747273, 361.81728227245236, 12330.630081097559, 16584.50246492604, 17238.999999999985, 17968.910155449237, 19185.904070479664, 15983.440579579717, 16006.0, 16064.116473593976, 16074.276657550408, 17057.744133837972, 4883.2430973199125, 4922.55696987271, 4922.585156328047, 4970.776315065813, 4985.40175178979, 3640.6541378568995, 4910.06708725341, 4996.5503104720565, 5000.19996800512, 5541.390447050821, 5850.063989761639, 5910.231669882909, 5911.438413350725, 5928.081147422156, 5943.3462319144855, 5733.455321744448, 5827.766889324422, 5852.180694702745, 5853.268341457275, 5905.763769449217, 5087.56755675768, 5095.726068482879, 5098.821541246039, 5100.056489549445, 5108.872278193075, 18647.0, 20892.686609700868, 21098.89450552753, 21278.255261065944, 22170.0, 1796.8203179682057, 1866.337450205171, 1872.9438116856516, 1881.4543782303115, 1899.8860068395802, 12266.509339626404, 13340.666483337896, 13764.242966636832, 13931.885449164043, 14149.151050936976, 1705.3860610180325, 1742.7821522309712, 1789.597340598368, 1810.0, 1840.5214644192472, 66331.66834165847, 70046.0, 70614.2938570618, 70869.0, 73576.26423735748, 9030.413023153475, 9393.295502837178, 9907.554160062815, 9919.107280344804, 9919.900800992042, 12973.935130324287, 12993.740125197442, 13020.348982551024, 13082.345882705828, 13138.343082845819, 7089.751858684921, 7177.425805935617, 7262.854742905112, 7398.0, 7419.777406677808, 6751.797446076625, 6779.525433219627, 6885.655717214119, 7012.088428504262, 7158.2125966143685, 6095.793902207462, 6177.444030037318, 6217.352401613485, 6230.570077669622, 6249.187605611241, 1617.5066604685685, 1664.4840099569171, 1671.548681855892, 1673.790776152981, 1685.8229885862054, 812.7155495576619, 835.9623816928131, 855.5593869157389, 872.5506364222431, 932.8087742012871, 17053.06208158563, 18118.0, 18610.534736631693, 18615.44153675392, 18930.81069189318, 854.9658013679383, 863.8833757442791, 875.3566128895256, 892.1791951404723, 894.1505569708803, 9292.256619470427, 10665.0, 10691.30506517074, 10737.907660762612, 10769.83845242322, 355049.0, 357513.0, 363401.0, 364039.0, 366819.0, 75926.0, 76336.0, 76360.0, 77102.61448692721, 78993.0, 886.9911300886909, 899.7705585075764, 919.3426699909596, 920.3833431600709, 924.9630014799333, 16303.918480407521, 16509.33962641529, 16854.41009564659, 17063.74179627836, 17084.829151708323, 38558.0, 38675.0, 38931.610683892795, 39236.0, 41240.793796030826, 48848.51151488582, 49213.26180107373, 50777.49222507728, 55390.44609553853, 58431.4156858426, 7479.962600186965, 7484.251574842472, 7632.3512501438, 7894.565798881116, 8042.9597852010365, 8981.7754556134, 9099.636014559343, 10638.255322127687, 11966.401679916144, 12409.689757756129, 10436.37381757097, 10451.831210191203, 10611.0, 10691.09125724291, 10756.946215268874, 4552.385427967182, 7426.443016773872, 9307.488088155216, 10592.152627789912, 10856.728581785519, 10634.627788027381, 10792.892071079188, 10882.564697412015, 10970.506327215135, 10974.122070234202, 7413.2957369049855, 7585.469017168745, 7592.112374369618, 7653.885191722017, 7845.137034926268, 9437.083610301648, 10600.1519878408, 10829.079528240189, 10888.29226100301, 10984.560617575207], "value": 8329.87365995375, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1214.872438393968, 1291.6964513339365, 1355.430719097979, 1389.5275606293858, 1659.4855594765625, 7048.823779405511, 7303.087114110736, 7373.520721153128, 7601.0, 7601.505902116359, 7399.667014984325, 7471.663775130119, 7479.62601869907, 7498.42533068055, 7505.249475052492, 1830.469163942455, 2113.17586141405, 2139.1122684086085, 2146.838987075968, 2157.492989147549, 2160.5354848707525, 2181.5745929543746, 2181.683655869898, 2183.170395249811, 2186.3331683836423, 2078.896055197242, 2091.1635345861623, 2106.083853523722, 2171.913123475067, 2176.1295481807324, 2026.0, 2073.2018173003353, 2076.678114892194, 2079.324219628627, 2102.9684554731693, 2144.8605840620467, 2144.8605840620467, 2154.4183070570857, 2160.2115227941845, 2161.6757486377014, 7720.459567830254, 7761.0298712660915, 7776.261255180749, 7777.144514103444, 7783.8443231135625, 4407.889802754925, 4540.296254080623, 4543.840965566223, 4551.135284295973, 4556.225441674923, 2777.5555911054335, 2932.88268469261, 2935.7064293570684, 2948.3661012882335, 2952.542355934812, 907.5371560504125, 933.3326671429892, 950.0406234579305, 961.1877963121151, 961.3078583419926, 10598.576056957712, 10603.78792424155, 10654.467276636215, 10673.999999999922, 10697.946510267477, 3720.516332876709, 3878.204967981557, 3974.5627980922077, 3994.6404823566018, 4003.259397011504, 8434.957825210835, 8435.198656127657, 8442.915570844212, 8443.240108390275, 8460.450070745383, 4422.557744225552, 4434.401355816924, 4439.42287502629, 4440.755758433253, 4459.1304695584695, 3643.562772467321, 3935.7835319057153, 4021.9195616087513, 4060.2894493463527, 4108.219438306712, 3266.281418088017, 3318.950215746766, 3396.694297513186, 3401.778884372508, 3425.760196786201, 1002.2132625888643, 1033.8811036730722, 1040.968770936858, 1058.3438268273567, 1064.595453727594, 738.8891666249998, 801.4349883332237, 805.3436449293807, 805.8428606421692, 807.8828569857455, 4848.7088926299775, 4924.9507504925205, 4980.551750342486, 5000.224965130411, 5057.468965758616, 398.1737893870231, 400.97393669411395, 414.9107941792529, 419.6391103650858, 421.4079218697735, 3268.346330733863, 3277.7869438486423, 3290.670932906738, 3345.347657206821, 3495.3184129094584, 9214.815703686023, 9582.185514231369, 9618.615255389706, 9640.566174522164, 9696.757581060388, 9513.619455221851, 9573.28200384974, 9581.329306948586, 9583.616655333848, 9585.90414095864, 7114.395276401564, 7181.856362872713, 7197.424206063502, 7208.92791072093, 7211.71153153868, 4735.039491946833, 4921.237208232729, 5004.874878128076, 5219.791208351623, 5306.416294207557, 8691.826163476568, 8838.867416988622, 8844.601992910206, 8847.51338676379, 8859.557022148738, 8415.873761893454, 8795.560221988748, 8812.383133180616, 8881.955590222266, 8884.37809353339, 5954.19618351517, 6433.517486188461, 6512.05575191604, 6568.507361947777, 6642.701078451385, 7255.854882902207, 7353.191148973716, 7371.926280737124, 7378.557286562824, 7383.741569045058, 6583.111279977142, 6676.499262555425, 6688.163979502562, 6689.063531105747, 6845.281245469353, 6521.282658907611, 6535.281119076993, 6568.113304703804, 6673.699683514156, 6722.899156512558, 6010.978133717366, 6084.695765211633, 6092.543059270661, 6093.939060609515, 6094.3600921904335, 4206.390073439273, 4385.912281754283, 4414.7792610368715, 4421.2262854001065, 4507.414036175276, 4176.164767046664, 4293.50624678166, 4297.699161058696, 4460.197164510289, 4470.798814053441], "value": 3919.294776151395, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.1131411422789, 297.3769951950663, 301.1312363830349, 305.4974566837551, 306.03446127467834, 4735.810567577297, 4743.644226683, 4748.596369308613, 4749.8812529686775, 4806.615470762339, 4689.413823272091, 4693.694909830859, 4703.506131856148, 4709.764511774415, 4711.293306004101, 3534.4344904815234, 3591.533100696906, 3736.178040831013, 3736.6823819975334, 3756.1548651553453, 3741.4013757798757, 3746.6253374662588, 3760.492333534965, 3761.4169803680475, 3769.8869033929027, 3725.4039353703415, 3733.831977561016, 3740.1397678533954, 3740.1397678533954, 3763.604821493745, 3567.3935430976794, 3595.7303202259936, 3693.316736403774, 3710.9072273193115, 3718.2377612589626, 3273.770836041454, 3282.0, 3296.2748195396975, 3296.686814752619, 3299.472084466498, 4609.101225261075, 4610.723356598614, 4621.537846215351, 4622.214223581999, 4631.9619367478535, 4158.688098392633, 4164.354525048592, 4203.327467605169, 4215.473065866767, 4224.619784219435, 4898.53463920927, 4927.285543596158, 4938.037082768861, 4940.950590494085, 4954.133026720309, 3618.294432585647, 3628.618995005539, 3636.636336366342, 3640.581333146669, 3642.4900513928073, 4947.406311242674, 5149.562287205555, 5170.302009228782, 5170.9741451292875, 5178.300929374562, 3674.52231209941, 3689.151495156129, 3697.7411581189594, 3701.8149092545264, 3708.2744183025457, 3733.2160246348026, 3733.4773131761576, 3741.4013757799166, 3742.476053352478, 3745.250949810049, 4220.388043733641, 4220.522817014082, 4220.53574106848, 4241.512226093977, 4246.171996460661, 4787.952120478751, 4904.5340692634145, 4930.2358134488495, 4943.505649435099, 4954.405471343462, 1285.1132718424258, 1299.2399446323818, 1301.225770666452, 1309.862464441239, 1317.5059352742721, 824.2993455562723, 844.0841686769924, 853.1638993786124, 856.2379482260793, 857.8455877941906, 4240.978795106066, 4347.261095561737, 4409.853438106052, 4429.446319210098, 4432.847459660511, 5026.773795179226, 5076.527806935947, 5098.388193416814, 5102.856800143948, 5116.130257856099, 4418.6023257906945, 4421.159979603929, 4425.800838962255, 4438.467383913951, 4446.688731788744, 2519.5590771614893, 2683.07433935292, 2688.8252263602803, 2695.9326016849736, 2704.4050308932005, 4784.354112194878, 4797.95202047982, 4799.616030717534, 4799.736014519235, 4808.374911261583, 4920.481855473354, 5138.665986710852, 5158.896822063538, 5172.974135129375, 5173.715445650525, 4780.689255198401, 4791.856244312677, 4798.8962538616315, 4821.614270858253, 4823.204171311694, 5036.194208926554, 5084.5932325414615, 5094.541491265803, 5097.1844504879045, 5106.8254301510115, 4751.7861696224445, 5001.674891132065, 5005.449400565862, 5015.22264049073, 5043.5712964398435, 4815.651617547092, 4842.709437433765, 4850.0, 4857.781399837087, 4859.586935110414, 3644.8359823807464, 3669.064388580895, 3690.2250527389547, 3698.500702405141, 3710.2394009227505, 5036.974815125901, 5159.613029022913, 5170.6380553360905, 5172.5603323716405, 5173.628988318023, 4973.041040580849, 4978.506448065494, 4990.950090499194, 4991.926735751831, 5011.348524691913, 4971.751412429437, 4991.401031876053, 4994.300797888372, 4998.400191976986, 5008.69947803133, 4731.29030645406, 4912.459629440687, 4932.1122198004705, 4937.28409380645, 4942.838432968178, 4798.880027999328, 4884.755762211804, 4944.258361245842, 4990.251462280687, 5004.174311238605, 4856.514348565115, 4859.854204373874, 4869.95130048695, 4875.341828853201, 4876.561109500162], "value": 3756.420383133124, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.2439977666108, 672.9427998620117, 684.4182444921814, 691.9792406227812, 692.6121372031663, 11897.40512974351, 13077.476900923975, 13091.14907531011, 13109.80335294969, 13127.540536081238, 12980.610581682564, 13083.542076027341, 13106.14810037347, 13124.93437532811, 13199.0, 592.0408937521222, 711.214108410207, 724.1527412926888, 737.4248086492545, 737.4358615659025, 744.709563270325, 745.2956955676888, 746.9215732348107, 746.9589172595505, 747.3087394160407, 777.8172129549552, 778.3500776851336, 779.1507257089771, 779.329776392305, 779.4582764978343, 624.8625302433458, 640.3916279534461, 641.2497378067626, 644.4296797334346, 774.9651265693029, 550.2104480071071, 565.0761005755578, 568.8122919436576, 569.1491220625151, 569.2087997683224, 11789.934616999642, 11817.527298908033, 11819.763604727943, 11826.11304152192, 11836.585719499777, 11610.883891161066, 11873.0, 12068.758624827542, 12412.689682758004, 12834.871651283462, 11289.943550282278, 11585.36280504572, 11631.941840290829, 11807.881921180764, 11940.820887686692, 3874.8062596870327, 3913.3151698452657, 3989.920201595981, 4094.996725802168, 4107.404426358191, 61195.69402153005, 61758.764824703256, 62369.12892613229, 63839.0, 65487.672561637366, 8837.823243535093, 8945.776355591031, 9015.0, 9068.229200517899, 9124.133207345292, 16012.439564615415, 16032.11823349727, 16057.518274451784, 16065.919670401807, 16830.0, 6749.42629876466, 6750.32496750321, 6757.8648427031185, 6849.623270720059, 7078.115235595551, 3505.158761897126, 3573.0352804743093, 3711.16498787776, 3826.0626146593704, 3861.8455261789777, 2956.541736030919, 3052.0538633023834, 3080.753539716817, 3083.629964404241, 3089.4902341113916, 502.60043265603935, 504.3065784546249, 509.9796008159632, 510.62979339978216, 516.2901011109724, 532.4595535531439, 550.5540512185063, 552.9281193444907, 559.2478116932718, 565.172022986327, 7503.737369192052, 7607.429442791813, 7993.360531157494, 8023.1575684553445, 8338.499690018618, 520.5965376832985, 544.3196004993757, 557.83543854563, 561.3236050559067, 569.9886002279932, 3630.5824830144343, 3660.890173294805, 3690.464882592009, 3710.461983012448, 3789.791561464146, 95109.5244523773, 100207.0, 101605.0, 103323.4833825826, 103394.0, 51523.48476515262, 61371.38628613746, 91227.0, 92148.0, 92252.53873730589, 79439.0, 79837.60081199557, 80919.0, 81326.0, 82337.58831205925, 7149.9285007149265, 7336.523125996793, 7360.227176146427, 7570.0, 7681.500702454323, 43241.783791080845, 44690.776546117064, 44761.0, 44850.102997939204, 45234.77382613066, 48558.5144148554, 61626.69186654038, 62317.0, 62897.0, 63456.682716586125, 8883.73348799537, 9372.39079459833, 9626.133647971716, 9808.705738827846, 9889.456079915673, 10578.471076446302, 10723.571057157626, 10882.782344353227, 11465.025472834903, 11501.597444089417, 9694.660686875926, 10238.232132589937, 10382.948085259526, 10430.113440357654, 10718.946405267923, 10113.797724045626, 10127.139193168663, 10323.122534584343, 10332.896671033193, 11184.71591759359, 11040.558377665126, 11156.776864462827, 11171.66485005451, 11207.159463040467, 11985.161038727205, 8615.82768344617, 8781.0, 9055.411398259092, 9064.728058158265, 9160.129787670161, 8055.0, 8274.213949674771, 8937.28501719848, 9283.535823208947, 9898.802023959337], "value": 6257.804783140164, "unit": "score"}], "name": "dromaeo_css", "value": 6272.806912812392, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 113.0, 114.0, 116.0, 115.0, 114.0, 105.0, 114.0, 115.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 121.0, 153.0, 190.0, 124.0, 147.0, 148.0, 122.0, 185.0, 128.0], "value": 148.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 163.0, 162.0, 163.0, 162.0, 161.0, 161.0, 161.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 180.0, 78.0, 76.0, 75.0, 77.0, 162.0, 76.0], "value": 94.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 159.0, 84.0, 83.0, 84.0, 139.0, 83.0, 83.0, 139.0], "value": 102.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 118.0, 118.0, 117.0, 117.0, 117.0, 119.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 63.0, 64.0, 67.0, 64.0, 77.0, 63.0, 63.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 64.0, 65.0, 65.0, 66.0, 65.0, 65.0, 66.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 81.0, 83.0, 85.0, 82.0, 87.0, 88.0, 82.0, 82.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 132.0, 132.0, 133.0, 130.0, 142.0, 141.0, 130.0, 187.0, 136.0], "value": 140.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 174.0, 175.0, 172.0, 175.0, 180.0, 174.0, 179.0, 183.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 69.0, 69.0, 71.0, 68.0, 68.0, 68.0, 68.0, 71.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1578.8, "alertThreshold": 2.0}]} 11:00:28 INFO - Return code: 0 11:00:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:00:28 INFO - # TBPL SUCCESS # 11:00:28 INFO - Running post-action listener: _resource_record_post_action 11:00:28 INFO - [mozharness: 2016-08-30 18:00:28.516415Z] Finished run-tests step (success) 11:00:28 INFO - Running post-run listener: _resource_record_post_run 11:00:28 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 406220800; Read time: 20; Write time: 328992 11:00:28 INFO - TinderboxPrint: CPU usage
13.0% 11:00:28 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 11:00:28 INFO - TinderboxPrint: I/O write bytes / time
406,220,800 / 328,992 11:00:28 INFO - TinderboxPrint: CPU idle
8,347.1 (87.0%) 11:00:28 INFO - TinderboxPrint: CPU user
1,201.0 (12.5%) 11:00:28 INFO - TinderboxPrint: Swap in / out
0 / 0 11:00:28 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114397184; Read time: 0; Write time: 128560 11:00:28 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 291823616; Read time: 0; Write time: 200432 11:00:29 INFO - Running post-run listener: _upload_blobber_files 11:00:29 INFO - Blob upload gear active. 11:00:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:00:29 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 11:00:29 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', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:00:29 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 Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:00:29 INFO - (blobuploader) - INFO - Open directory for files ... 11:00:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:00:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:00:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:00:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:00:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:00:30 INFO - (blobuploader) - INFO - Done attempting. 11:00:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:00:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:00:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:00:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:00:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:00:37 INFO - (blobuploader) - INFO - Done attempting. 11:00:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:00:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:00:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:00:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:00:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:00:38 INFO - (blobuploader) - INFO - Done attempting. 11:00:38 INFO - (blobuploader) - INFO - Iteration through files over. 11:00:38 INFO - Return code: 0 11:00:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:00:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:00:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/f4f3c5c0c2ace757d2414fa170ad34e82b616508efe8511989b46458d88e74bf0118f5aced04c0092afaa0bb461a51de4407bce305436571b0c174e81ae917ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/56db213500f3107b06a12f16c1a59b486d715482285c3caaa9138d789428df0ded42627956ef32ea7057222c958112027a15ecfe6405c00d76ca1144bda00570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/b917a0e2444b9811e103ff2eb342ffe9cb3be6c70592253824c83ebeb5e0a760730d0df6801f4c29ebd028e7114954b6c46dce49b220acdb44235b0884fa280c"} 11:00:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:00:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:00:38 INFO - Contents: 11:00:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/f4f3c5c0c2ace757d2414fa170ad34e82b616508efe8511989b46458d88e74bf0118f5aced04c0092afaa0bb461a51de4407bce305436571b0c174e81ae917ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/56db213500f3107b06a12f16c1a59b486d715482285c3caaa9138d789428df0ded42627956ef32ea7057222c958112027a15ecfe6405c00d76ca1144bda00570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/b917a0e2444b9811e103ff2eb342ffe9cb3be6c70592253824c83ebeb5e0a760730d0df6801f4c29ebd028e7114954b6c46dce49b220acdb44235b0884fa280c"} 11:00:38 INFO - Running post-run listener: copy_logs_to_upload_dir 11:00:38 INFO - Copying logs to upload dir... 11:00:38 INFO - mkdir: /builds/slave/test/build/upload/logs 11:00:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.865905 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-08-30 11:00:38.206050) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 11:00:38.210089) ========= 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=a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/f4f3c5c0c2ace757d2414fa170ad34e82b616508efe8511989b46458d88e74bf0118f5aced04c0092afaa0bb461a51de4407bce305436571b0c174e81ae917ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/56db213500f3107b06a12f16c1a59b486d715482285c3caaa9138d789428df0ded42627956ef32ea7057222c958112027a15ecfe6405c00d76ca1144bda00570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/b917a0e2444b9811e103ff2eb342ffe9cb3be6c70592253824c83ebeb5e0a760730d0df6801f4c29ebd028e7114954b6c46dce49b220acdb44235b0884fa280c"} build_url:https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011408 build_url: 'https://queue.taskcluster.net/v1/task/H5TaPvWxRL-FuaQQ8wAQ3A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/f4f3c5c0c2ace757d2414fa170ad34e82b616508efe8511989b46458d88e74bf0118f5aced04c0092afaa0bb461a51de4407bce305436571b0c174e81ae917ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/56db213500f3107b06a12f16c1a59b486d715482285c3caaa9138d789428df0ded42627956ef32ea7057222c958112027a15ecfe6405c00d76ca1144bda00570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/b917a0e2444b9811e103ff2eb342ffe9cb3be6c70592253824c83ebeb5e0a760730d0df6801f4c29ebd028e7114954b6c46dce49b220acdb44235b0884fa280c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 11:00:38.236086) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 11:00:38.236425) ========= 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=a0d2d2de893abaa8702a9954000001a7-1472577989.183212-2087318461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004458 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 11:00:38.321043) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 11:00:38.321714) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 11:00:38.322328) ========= ========= Total master_lag: 0.44 =========