builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-039 starttime: 1472549627.27 results: success (0) buildid: 20160830010820 builduid: 7e2903cb159945c898d40f58c0c1aeb0 revision: bc60cb47882ac16cd4bade1e2d0e2623130f2e9b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 02:33:47.274229) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 02:33:47.274911) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 02:33:47.275241) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 02:33:47.311819) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 02:33:47.312124) ========= 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=5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-30 02:33:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 23.1M=0.001s 2016-08-30 02:33:48 (23.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080077 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 02:33:47.408353) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 02:33:47.408846) ========= 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=5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017638 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 02:33:47.469229) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 02:33:47.469546) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev bc60cb47882ac16cd4bade1e2d0e2623130f2e9b --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 bc60cb47882ac16cd4bade1e2d0e2623130f2e9b --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=5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-30 02:33:48,298 truncating revision to first 12 chars 2016-08-30 02:33:48,298 Setting DEBUG logging. 2016-08-30 02:33:48,299 attempt 1/10 2016-08-30 02:33:48,299 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/bc60cb47882a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 02:33:48,557 unpacking tar archive at: larch-bc60cb47882a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.533046 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 02:33:48.019632) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 02:33:48.019935) ========= script_repo_revision: bc60cb47882ac16cd4bade1e2d0e2623130f2e9b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 02:33:48.020362) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 02:33:48.020689) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 02:33:48.033463) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-30 02:33:48.033835) ========= /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=5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:33:48 INFO - MultiFileLogger online at 20160830 02:33:48 in /builds/slave/test 02:33:48 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 02:33:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:33:48 INFO - {'append_to_log': False, 02:33:48 INFO - 'base_work_dir': '/builds/slave/test', 02:33:48 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 02:33:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:33:48 INFO - 'branch': 'Larch-Non-PGO', 02:33:48 INFO - 'buildbot_json_path': 'buildprops.json', 02:33:48 INFO - 'config_files': ('talos/linux_config.py',), 02:33:48 INFO - 'default_actions': ('clobber', 02:33:48 INFO - 'read-buildbot-config', 02:33:48 INFO - 'download-and-extract', 02:33:48 INFO - 'populate-webroot', 02:33:48 INFO - 'create-virtualenv', 02:33:48 INFO - 'install', 02:33:48 INFO - 'run-tests'), 02:33:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:33:48 INFO - 'download_minidump_stackwalk': True, 02:33:48 INFO - 'download_symbols': 'ondemand', 02:33:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:33:48 INFO - 'tooltool.py': '/tools/tooltool.py', 02:33:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:33:48 INFO - '/tools/misc-python/virtualenv.py')}, 02:33:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:33:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:33:48 INFO - 'installer_path': 'installer.exe', 02:33:48 INFO - 'log_level': 'info', 02:33:48 INFO - 'log_name': 'talos', 02:33:48 INFO - 'log_to_console': True, 02:33:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:33:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:33:48 INFO - 'opt_config_files': (), 02:33:48 INFO - 'pip_index': False, 02:33:48 INFO - 'sps_profile': False, 02:33:48 INFO - 'sps_profile_interval': 0, 02:33:48 INFO - 'suite': 'dromaeojs', 02:33:48 INFO - 'system_bits': '32', 02:33:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:33:48 INFO - 'title': 'talos-linux64-ix-039', 02:33:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:33:48 INFO - 'use_talos_json': True, 02:33:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:33:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:33:48 INFO - 'work_dir': 'build'} 02:33:48 INFO - [mozharness: 2016-08-30 09:33:48.959256Z] Running clobber step. 02:33:48 INFO - Running pre-action listener: _resource_record_pre_action 02:33:48 INFO - Running main action method: clobber 02:33:48 INFO - rmtree: /builds/slave/test/build 02:33:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:33:49 INFO - Running post-action listener: _resource_record_post_action 02:33:49 INFO - [mozharness: 2016-08-30 09:33:49.433795Z] Finished clobber step (success) 02:33:49 INFO - [mozharness: 2016-08-30 09:33:49.433899Z] Running read-buildbot-config step. 02:33:49 INFO - Running pre-action listener: _resource_record_pre_action 02:33:49 INFO - Running main action method: read_buildbot_config 02:33:49 INFO - Using buildbot properties: 02:33:49 INFO - { 02:33:49 INFO - "project": "", 02:33:49 INFO - "product": "firefox", 02:33:49 INFO - "script_repo_revision": "production", 02:33:49 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 02:33:49 INFO - "repository": "", 02:33:49 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 02:33:49 INFO - "buildid": "20160830010820", 02:33:49 INFO - "pgo_build": "False", 02:33:49 INFO - "basedir": "/builds/slave/test", 02:33:49 INFO - "buildnumber": 4, 02:33:49 INFO - "slavename": "talos-linux64-ix-039", 02:33:49 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 02:33:49 INFO - "platform": "ubuntu64_hw", 02:33:49 INFO - "scheduler": "tests-larch-linux64-talos", 02:33:49 INFO - "branch": "larch", 02:33:49 INFO - "repo_path": "projects/larch", 02:33:49 INFO - "revision": "bc60cb47882ac16cd4bade1e2d0e2623130f2e9b", 02:33:49 INFO - "stage_platform": "linux64", 02:33:49 INFO - "builduid": "7e2903cb159945c898d40f58c0c1aeb0", 02:33:49 INFO - "slavebuilddir": "test" 02:33:49 INFO - } 02:33:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 02:33:49 INFO - Running post-action listener: _resource_record_post_action 02:33:49 INFO - [mozharness: 2016-08-30 09:33:49.436410Z] Finished read-buildbot-config step (success) 02:33:49 INFO - [mozharness: 2016-08-30 09:33:49.436501Z] Running download-and-extract step. 02:33:49 INFO - Running pre-action listener: _resource_record_pre_action 02:33:49 INFO - Running main action method: download_and_extract 02:33:49 INFO - mkdir: /builds/slave/test/build/tests 02:33:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:33:49 INFO - trying https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 02:33:49 INFO - Downloading https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/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 02:33:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/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 02:33:49 INFO - Downloaded 1361 bytes. 02:33:49 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 02:33:49 INFO - Using the following test package requirements: 02:33:49 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 02:33:49 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:33:49 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:33:49 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:33:49 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:33:49 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:33:49 INFO - u'jsshell-linux-x86_64.zip'], 02:33:49 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:33:49 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:33:49 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 02:33:49 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:33:49 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:33:49 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:33:49 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:33:49 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:33:49 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:33:49 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:33:49 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:33:49 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:33:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:33:49 INFO - trying https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:33:49 INFO - Downloading https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/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 02:33:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/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 02:33:50 INFO - Downloaded 20305117 bytes. 02:33:50 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 02:33:55 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 02:33:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:33:55 INFO - trying https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:33:55 INFO - Downloading https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/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 02:33:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/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 02:33:55 INFO - Downloaded 20305117 bytes. 02:33:55 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 02:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:00 INFO - trying https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 02:34:00 INFO - Downloading https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/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 02:34:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/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 02:34:01 INFO - Downloaded 14413998 bytes. 02:34:01 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 02:34:01 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')]} 02:34:01 INFO - trying https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:34:01 INFO - Downloading https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/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 02:34:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/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 02:34:02 INFO - Downloaded 55887035 bytes. 02:34:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:34:02 INFO - mkdir: /builds/slave/test/properties 02:34:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:34:02 INFO - Writing to file /builds/slave/test/properties/build_url 02:34:02 INFO - Contents: 02:34:02 INFO - build_url:https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:34:03 INFO - Running post-action listener: _resource_record_post_action 02:34:03 INFO - Running post-action listener: set_extra_try_arguments 02:34:03 INFO - [mozharness: 2016-08-30 09:34:03.598988Z] Finished download-and-extract step (success) 02:34:03 INFO - [mozharness: 2016-08-30 09:34:03.599292Z] Running populate-webroot step. 02:34:03 INFO - Running pre-action listener: _resource_record_pre_action 02:34:03 INFO - Running main action method: populate_webroot 02:34:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:34:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:34:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:34:03 INFO - '--tppagecycles', 02:34:03 INFO - '7'], 02:34:03 INFO - 'tests': ['tsvgm']}}, 02:34:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:34:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:34:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:34:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:34:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:34:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:34:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:03 INFO - 'talos_options': ['--disable-e10s'], 02:34:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:34:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:34:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:03 INFO - 'talos_options': ['--disable-e10s'], 02:34:03 INFO - 'tests': ['damp', 'tps']}, 02:34:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:03 INFO - 'tests': ['damp', 'tps']}, 02:34:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:34:03 INFO - 'tests': ['dromaeo_dom']}, 02:34:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:34:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:34:03 INFO - 'tests': ['basic_compositor_video']}, 02:34:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:34:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:34:03 INFO - 'tests': ['a11yr', 02:34:03 INFO - 'ts_paint', 02:34:03 INFO - 'tpaint', 02:34:03 INFO - 'sessionrestore', 02:34:03 INFO - 'sessionrestore_no_auto_restore', 02:34:03 INFO - 'tabpaint']}, 02:34:03 INFO - 'other-e10s': {'tests': ['a11yr', 02:34:03 INFO - 'ts_paint', 02:34:03 INFO - 'tpaint', 02:34:03 INFO - 'sessionrestore', 02:34:03 INFO - 'sessionrestore_no_auto_restore', 02:34:03 INFO - 'tabpaint']}, 02:34:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:34:03 INFO - 'ts_paint', 02:34:03 INFO - 'tpaint', 02:34:03 INFO - 'sessionrestore', 02:34:03 INFO - 'sessionrestore_no_auto_restore', 02:34:03 INFO - 'tabpaint']}, 02:34:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:34:03 INFO - 'ts_paint', 02:34:03 INFO - 'tpaint', 02:34:03 INFO - 'sessionrestore', 02:34:03 INFO - 'sessionrestore_no_auto_restore', 02:34:03 INFO - 'tabpaint']}, 02:34:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:34:03 INFO - 'tests': ['a11yr', 02:34:03 INFO - 'ts_paint', 02:34:03 INFO - 'tpaint', 02:34:03 INFO - 'sessionrestore', 02:34:03 INFO - 'sessionrestore_no_auto_restore', 02:34:03 INFO - 'tabpaint']}, 02:34:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:34:03 INFO - 'tests': ['a11yr', 02:34:03 INFO - 'ts_paint', 02:34:03 INFO - 'tpaint', 02:34:03 INFO - 'sessionrestore', 02:34:03 INFO - 'sessionrestore_no_auto_restore', 02:34:03 INFO - 'tabpaint']}, 02:34:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:34:03 INFO - 'tests': ['tsvgx', 02:34:03 INFO - 'tsvgr_opacity', 02:34:03 INFO - 'tart', 02:34:03 INFO - 'tscrollx', 02:34:03 INFO - 'cart']}, 02:34:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:34:03 INFO - 'tsvgr_opacity', 02:34:03 INFO - 'tart', 02:34:03 INFO - 'tscrollx', 02:34:03 INFO - 'cart']}, 02:34:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:03 INFO - 'talos_options': ['--disable-e10s'], 02:34:03 INFO - 'tests': ['tp5o']}, 02:34:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:03 INFO - 'tests': ['tp5o']}, 02:34:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:03 INFO - 'talos_options': ['--disable-e10s', 02:34:03 INFO - '--xperf_path', 02:34:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:34:03 INFO - 'tests': ['tp5n']}, 02:34:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:03 INFO - 'talos_options': ['--xperf_path', 02:34:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:34:03 INFO - 'tests': ['tp5n']}}, 02:34:03 INFO - 'talos.zip': {'path': '', 02:34:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:34:03 INFO - Running post-action listener: _resource_record_post_action 02:34:03 INFO - [mozharness: 2016-08-30 09:34:03.621632Z] Finished populate-webroot step (success) 02:34:03 INFO - [mozharness: 2016-08-30 09:34:03.621728Z] Running create-virtualenv step. 02:34:03 INFO - Running pre-action listener: _resource_record_pre_action 02:34:03 INFO - Running main action method: create_virtualenv 02:34:03 INFO - Creating virtualenv /builds/slave/test/build/venv 02:34:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:34:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:34:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:34:03 INFO - Using real prefix '/usr' 02:34:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:34:04 INFO - Installing distribute.............................................................................................................................................................................................done. 02:34:06 INFO - Installing pip.................done. 02:34:06 INFO - Return code: 0 02:34:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:06 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 0x7fb9efdcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb090>, '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 0x1ece9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecee40>, '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': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:34:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:34:06 INFO - Using env: {'DISPLAY': ':0', 02:34:06 INFO - 'HOME': '/home/cltbld', 02:34:06 INFO - 'LANG': 'en_US.UTF-8', 02:34:06 INFO - 'LANGUAGE': 'en_US:en', 02:34:06 INFO - 'LOGNAME': 'cltbld', 02:34:06 INFO - 'MAIL': '/var/mail/cltbld', 02:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:34:06 INFO - 'MOZ_NO_REMOTE': '1', 02:34:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:34:06 INFO - 'NO_EM_RESTART': '1', 02:34:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:34:06 INFO - 'PWD': '/builds/slave/test', 02:34:06 INFO - 'SHELL': '/bin/bash', 02:34:06 INFO - 'SHLVL': '1', 02:34:06 INFO - 'TERM': 'linux', 02:34:06 INFO - 'TMOUT': '86400', 02:34:06 INFO - 'USER': 'cltbld', 02:34:06 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 02:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:34:06 INFO - '_': '/tools/buildbot/bin/python'} 02:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:34:06 INFO - Cleaning up... 02:34:06 INFO - Return code: 0 02:34:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:06 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 0x7fb9efdcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb090>, '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 0x1ece9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecee40>, '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': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:34:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:34:06 INFO - Using env: {'DISPLAY': ':0', 02:34:06 INFO - 'HOME': '/home/cltbld', 02:34:06 INFO - 'LANG': 'en_US.UTF-8', 02:34:06 INFO - 'LANGUAGE': 'en_US:en', 02:34:06 INFO - 'LOGNAME': 'cltbld', 02:34:06 INFO - 'MAIL': '/var/mail/cltbld', 02:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:34:06 INFO - 'MOZ_NO_REMOTE': '1', 02:34:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:34:06 INFO - 'NO_EM_RESTART': '1', 02:34:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:34:06 INFO - 'PWD': '/builds/slave/test', 02:34:06 INFO - 'SHELL': '/bin/bash', 02:34:06 INFO - 'SHLVL': '1', 02:34:06 INFO - 'TERM': 'linux', 02:34:06 INFO - 'TMOUT': '86400', 02:34:06 INFO - 'USER': 'cltbld', 02:34:06 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 02:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:34:06 INFO - '_': '/tools/buildbot/bin/python'} 02:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:34:06 INFO - Downloading/unpacking psutil>=3.1.1 02:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:34:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:34:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:34:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:34:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:34:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:34:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:34:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:34:08 INFO - Installing collected packages: psutil 02:34:08 INFO - Running setup.py install for psutil 02:34:08 INFO - building 'psutil._psutil_linux' extension 02:34: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 02:34: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 02:34:08 INFO - building 'psutil._psutil_posix' extension 02:34: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 02:34:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 02:34:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:34:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:34:09 INFO - Successfully installed psutil 02:34:09 INFO - Cleaning up... 02:34:09 INFO - Return code: 0 02:34:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:34:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:34:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:09 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 0x7fb9efdcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb090>, '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 0x1ece9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecee40>, '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': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:34:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:34:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:34:09 INFO - Using env: {'DISPLAY': ':0', 02:34:09 INFO - 'HOME': '/home/cltbld', 02:34:09 INFO - 'LANG': 'en_US.UTF-8', 02:34:09 INFO - 'LANGUAGE': 'en_US:en', 02:34:09 INFO - 'LOGNAME': 'cltbld', 02:34:09 INFO - 'MAIL': '/var/mail/cltbld', 02:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:34:09 INFO - 'MOZ_NO_REMOTE': '1', 02:34:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:34:09 INFO - 'NO_EM_RESTART': '1', 02:34:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:34:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:34:09 INFO - 'PWD': '/builds/slave/test', 02:34:09 INFO - 'SHELL': '/bin/bash', 02:34:09 INFO - 'SHLVL': '1', 02:34:09 INFO - 'TERM': 'linux', 02:34:09 INFO - 'TMOUT': '86400', 02:34:09 INFO - 'USER': 'cltbld', 02:34:09 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 02:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:34:09 INFO - '_': '/tools/buildbot/bin/python'} 02:34:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:34:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:34:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:34:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:34:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:34:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:34:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:34:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:34:11 INFO - Installing collected packages: mozsystemmonitor 02:34:11 INFO - Running setup.py install for mozsystemmonitor 02:34:11 INFO - Successfully installed mozsystemmonitor 02:34:11 INFO - Cleaning up... 02:34:11 INFO - Return code: 0 02:34:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:34:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:34:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:11 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 0x7fb9efdcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb090>, '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 0x1ece9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecee40>, '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': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:34:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:34:11 INFO - Using env: {'DISPLAY': ':0', 02:34:11 INFO - 'HOME': '/home/cltbld', 02:34:11 INFO - 'LANG': 'en_US.UTF-8', 02:34:11 INFO - 'LANGUAGE': 'en_US:en', 02:34:11 INFO - 'LOGNAME': 'cltbld', 02:34:11 INFO - 'MAIL': '/var/mail/cltbld', 02:34:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:34:11 INFO - 'MOZ_NO_REMOTE': '1', 02:34:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:34:11 INFO - 'NO_EM_RESTART': '1', 02:34:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:34:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:34:11 INFO - 'PWD': '/builds/slave/test', 02:34:11 INFO - 'SHELL': '/bin/bash', 02:34:11 INFO - 'SHLVL': '1', 02:34:11 INFO - 'TERM': 'linux', 02:34:11 INFO - 'TMOUT': '86400', 02:34:11 INFO - 'USER': 'cltbld', 02:34:11 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 02:34:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:34:11 INFO - '_': '/tools/buildbot/bin/python'} 02:34:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:34:11 INFO - Downloading/unpacking jsonschema==2.5.1 02:34: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 02:34: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 02:34: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 02:34: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 02:34: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 02:34: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 02:34:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:34:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:34: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 02:34: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 02:34: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 02:34: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 02:34: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 02:34: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 02:34:14 INFO - Downloading functools32-3.2.3-2.tar.gz 02:34:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:34:14 INFO - warning: no files found matching '*.txt' 02:34:14 INFO - no previously-included directories found matching 'build' 02:34:14 INFO - no previously-included directories found matching 'dist' 02:34:14 INFO - no previously-included directories found matching '.git*' 02:34:14 INFO - Installing collected packages: jsonschema, functools32 02:34:14 INFO - Running setup.py install for functools32 02:34:14 INFO - warning: no files found matching '*.txt' 02:34:14 INFO - no previously-included directories found matching 'build' 02:34:14 INFO - no previously-included directories found matching 'dist' 02:34:14 INFO - no previously-included directories found matching '.git*' 02:34:14 INFO - Successfully installed jsonschema functools32 02:34:14 INFO - Cleaning up... 02:34:14 INFO - Return code: 0 02:34:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:34:14 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')]} 02:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:34:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:14 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')]} 02:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:34:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:14 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 0x7fb9efdcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb090>, '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 0x1ece9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecee40>, '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': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:34:14 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 02:34:14 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 02:34:14 INFO - Using env: {'DISPLAY': ':0', 02:34:14 INFO - 'HOME': '/home/cltbld', 02:34:14 INFO - 'LANG': 'en_US.UTF-8', 02:34:14 INFO - 'LANGUAGE': 'en_US:en', 02:34:14 INFO - 'LOGNAME': 'cltbld', 02:34:14 INFO - 'MAIL': '/var/mail/cltbld', 02:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:34:14 INFO - 'MOZ_NO_REMOTE': '1', 02:34:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:34:14 INFO - 'NO_EM_RESTART': '1', 02:34:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:34:14 INFO - 'PWD': '/builds/slave/test', 02:34:14 INFO - 'SHELL': '/bin/bash', 02:34:14 INFO - 'SHLVL': '1', 02:34:14 INFO - 'TERM': 'linux', 02:34:14 INFO - 'TMOUT': '86400', 02:34:14 INFO - 'USER': 'cltbld', 02:34:14 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 02:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:34:14 INFO - '_': '/tools/buildbot/bin/python'} 02:34:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:34:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:34:14 INFO - Cleaning up... 02:34:14 INFO - Return code: 0 02:34:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:34:14 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')]} 02:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:34:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:14 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')]} 02:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:34:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:14 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 0x7fb9efdcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb090>, '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 0x1ece9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecee40>, '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': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:34:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:34:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:34:14 INFO - Using env: {'DISPLAY': ':0', 02:34:14 INFO - 'HOME': '/home/cltbld', 02:34:14 INFO - 'LANG': 'en_US.UTF-8', 02:34:14 INFO - 'LANGUAGE': 'en_US:en', 02:34:14 INFO - 'LOGNAME': 'cltbld', 02:34:14 INFO - 'MAIL': '/var/mail/cltbld', 02:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:34:14 INFO - 'MOZ_NO_REMOTE': '1', 02:34:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:34:14 INFO - 'NO_EM_RESTART': '1', 02:34:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:34:14 INFO - 'PWD': '/builds/slave/test', 02:34:14 INFO - 'SHELL': '/bin/bash', 02:34:14 INFO - 'SHLVL': '1', 02:34:14 INFO - 'TERM': 'linux', 02:34:14 INFO - 'TMOUT': '86400', 02:34:14 INFO - 'USER': 'cltbld', 02:34:14 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 02:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:34:14 INFO - '_': '/tools/buildbot/bin/python'} 02:34:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:34:14 INFO - Downloading/unpacking blobuploader==1.2.4 02:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:34:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:34:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:34:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:34:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:34:16 INFO - Downloading blobuploader-1.2.4.tar.gz 02:34:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:34:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:34: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 02:34: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 02:34: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 02:34: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 02:34: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 02:34: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 02:34:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:34:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:34:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:34:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:34:17 INFO - Downloading docopt-0.6.1.tar.gz 02:34:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:34:17 INFO - Installing collected packages: blobuploader, requests, docopt 02:34:17 INFO - Running setup.py install for blobuploader 02:34:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:34:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:34:17 INFO - Running setup.py install for requests 02:34:17 INFO - Running setup.py install for docopt 02:34:17 INFO - Successfully installed blobuploader requests docopt 02:34:17 INFO - Cleaning up... 02:34:17 INFO - Return code: 0 02:34:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:34: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')]} 02:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:34:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34: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')]} 02:34:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:34:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34: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 0x7fb9efdcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb090>, '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 0x1ece9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecee40>, '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': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:34: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 02:34: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 02:34:17 INFO - Using env: {'DISPLAY': ':0', 02:34:17 INFO - 'HOME': '/home/cltbld', 02:34:17 INFO - 'LANG': 'en_US.UTF-8', 02:34:17 INFO - 'LANGUAGE': 'en_US:en', 02:34:17 INFO - 'LOGNAME': 'cltbld', 02:34:17 INFO - 'MAIL': '/var/mail/cltbld', 02:34:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:34:17 INFO - 'MOZ_NO_REMOTE': '1', 02:34:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:34:17 INFO - 'NO_EM_RESTART': '1', 02:34:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:34:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:34:17 INFO - 'PWD': '/builds/slave/test', 02:34:17 INFO - 'SHELL': '/bin/bash', 02:34:17 INFO - 'SHLVL': '1', 02:34:17 INFO - 'TERM': 'linux', 02:34:17 INFO - 'TMOUT': '86400', 02:34:17 INFO - 'USER': 'cltbld', 02:34:17 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 02:34:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:34:17 INFO - '_': '/tools/buildbot/bin/python'} 02:34:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:34:18 INFO - Running setup.py (path:/tmp/pip-uHo8ig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:34:18 INFO - Running setup.py (path:/tmp/pip-XRMP_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:34:18 INFO - Running setup.py (path:/tmp/pip-XhhXU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:34:18 INFO - Running setup.py (path:/tmp/pip-wEA6LQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:34:18 INFO - Running setup.py (path:/tmp/pip-q5sCj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:34:18 INFO - Running setup.py (path:/tmp/pip-UNG8Kv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:34:18 INFO - Running setup.py (path:/tmp/pip-j3FRCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:34:18 INFO - Running setup.py (path:/tmp/pip-OUUyYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:34:18 INFO - Running setup.py (path:/tmp/pip-VBvF_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:34:18 INFO - Running setup.py (path:/tmp/pip-y0mxD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:34:18 INFO - Running setup.py (path:/tmp/pip-gPP4Nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:34:18 INFO - Running setup.py (path:/tmp/pip-wGYWuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:34:19 INFO - Running setup.py (path:/tmp/pip-5Rnmeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:34:19 INFO - Running setup.py (path:/tmp/pip-M9eM4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:34:19 INFO - Running setup.py (path:/tmp/pip-jbqXaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:34:19 INFO - Running setup.py (path:/tmp/pip-MYk9k8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:34:19 INFO - Running setup.py (path:/tmp/pip-0ELmPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:34:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:34:19 INFO - Running setup.py install for manifestparser 02:34:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:34:19 INFO - Running setup.py install for mozcrash 02:34:19 INFO - Running setup.py install for mozdebug 02:34:19 INFO - Running setup.py install for mozdevice 02:34:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:34:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:34:19 INFO - Running setup.py install for mozfile 02:34:19 INFO - Running setup.py install for mozhttpd 02:34:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:34:20 INFO - Running setup.py install for mozinfo 02:34:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:34:20 INFO - Running setup.py install for mozInstall 02:34:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:34:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:34:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:34:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:34:20 INFO - Running setup.py install for mozleak 02:34:20 INFO - Running setup.py install for mozlog 02:34:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:34:20 INFO - Running setup.py install for moznetwork 02:34:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:34:20 INFO - Running setup.py install for mozprocess 02:34:20 INFO - Running setup.py install for mozprofile 02:34:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:34:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:34:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:34:20 INFO - Running setup.py install for mozrunner 02:34:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:34:20 INFO - Running setup.py install for mozscreenshot 02:34:20 INFO - Running setup.py install for moztest 02:34:21 INFO - Running setup.py install for mozversion 02:34:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:34:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:34:21 INFO - Cleaning up... 02:34:21 INFO - Return code: 0 02:34:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:34:21 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')]} 02:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:34:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:21 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')]} 02:34:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:34:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:21 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 0x7fb9efdcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb090>, '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 0x1ece9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecee40>, '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': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:34:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:34:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:34:21 INFO - Using env: {'DISPLAY': ':0', 02:34:21 INFO - 'HOME': '/home/cltbld', 02:34:21 INFO - 'LANG': 'en_US.UTF-8', 02:34:21 INFO - 'LANGUAGE': 'en_US:en', 02:34:21 INFO - 'LOGNAME': 'cltbld', 02:34:21 INFO - 'MAIL': '/var/mail/cltbld', 02:34:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:34:21 INFO - 'MOZ_NO_REMOTE': '1', 02:34:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:34:21 INFO - 'NO_EM_RESTART': '1', 02:34:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:34:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:34:21 INFO - 'PWD': '/builds/slave/test', 02:34:21 INFO - 'SHELL': '/bin/bash', 02:34:21 INFO - 'SHLVL': '1', 02:34:21 INFO - 'TERM': 'linux', 02:34:21 INFO - 'TMOUT': '86400', 02:34:21 INFO - 'USER': 'cltbld', 02:34:21 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 02:34:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:34:21 INFO - '_': '/tools/buildbot/bin/python'} 02:34:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:34:21 INFO - Running setup.py (path:/tmp/pip-lSUuzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:34:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:34:21 INFO - Running setup.py (path:/tmp/pip-rJpd1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:34:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:34:21 INFO - Running setup.py (path:/tmp/pip-rn0wIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:34:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:34:21 INFO - Running setup.py (path:/tmp/pip-d61pme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:34:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:34:21 INFO - Running setup.py (path:/tmp/pip-HlxVsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:34:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:34:21 INFO - Running setup.py (path:/tmp/pip-rvgDCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:34:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:34:21 INFO - Running setup.py (path:/tmp/pip-0WY_ND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:34: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)) 02:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:34:21 INFO - Running setup.py (path:/tmp/pip-BDfVye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:34: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)) 02:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:34:22 INFO - Running setup.py (path:/tmp/pip-kC396C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:34:22 INFO - Running setup.py (path:/tmp/pip-c_r1Bm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:34:22 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)) 02:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:34:22 INFO - Running setup.py (path:/tmp/pip-VlaVC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:34:22 INFO - Running setup.py (path:/tmp/pip-N1lRdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:34:22 INFO - Running setup.py (path:/tmp/pip-78CVpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:34:22 INFO - Running setup.py (path:/tmp/pip-ABVMDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:34:22 INFO - Running setup.py (path:/tmp/pip-uX9_3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:34:22 INFO - Running setup.py (path:/tmp/pip-uJpnex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:34:22 INFO - Running setup.py (path:/tmp/pip-K1K__x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:34:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:34:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:34:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:34:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:34:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:34:24 INFO - Downloading blessings-1.6.tar.gz 02:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:34:24 INFO - Installing collected packages: blessings 02:34:24 INFO - Running setup.py install for blessings 02:34:24 INFO - Successfully installed blessings 02:34:24 INFO - Cleaning up... 02:34:24 INFO - Return code: 0 02:34:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:34:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:34:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:34:25 INFO - Reading from file tmpfile_stdout 02:34:25 INFO - Current package versions: 02:34:25 INFO - argparse == 1.2.1 02:34:25 INFO - blessings == 1.6 02:34:25 INFO - blobuploader == 1.2.4 02:34:25 INFO - docopt == 0.6.1 02:34:25 INFO - functools32 == 3.2.3-2 02:34:25 INFO - jsonschema == 2.5.1 02:34:25 INFO - manifestparser == 1.1 02:34:25 INFO - mozInstall == 1.12 02:34:25 INFO - mozcrash == 0.17 02:34:25 INFO - mozdebug == 0.1 02:34:25 INFO - mozdevice == 0.48 02:34:25 INFO - mozfile == 1.2 02:34:25 INFO - mozhttpd == 0.7 02:34:25 INFO - mozinfo == 0.9 02:34:25 INFO - mozleak == 0.1 02:34:25 INFO - mozlog == 3.3 02:34:25 INFO - moznetwork == 0.27 02:34:25 INFO - mozprocess == 0.23 02:34:25 INFO - mozprofile == 0.28 02:34:25 INFO - mozrunner == 6.12 02:34:25 INFO - mozscreenshot == 0.1 02:34:25 INFO - mozsystemmonitor == 0.3 02:34:25 INFO - moztest == 0.7 02:34:25 INFO - mozversion == 1.4 02:34:25 INFO - psutil == 3.1.1 02:34:25 INFO - requests == 1.2.3 02:34:25 INFO - wsgiref == 0.1.2 02:34:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:34:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:34:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:25 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 0x7fb9efdcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb090>, '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 0x1ece9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecee40>, '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': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:34:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:34:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:34:25 INFO - Using env: {'DISPLAY': ':0', 02:34:25 INFO - 'HOME': '/home/cltbld', 02:34:25 INFO - 'LANG': 'en_US.UTF-8', 02:34:25 INFO - 'LANGUAGE': 'en_US:en', 02:34:25 INFO - 'LOGNAME': 'cltbld', 02:34:25 INFO - 'MAIL': '/var/mail/cltbld', 02:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:34:25 INFO - 'MOZ_NO_REMOTE': '1', 02:34:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:34:25 INFO - 'NO_EM_RESTART': '1', 02:34:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:34:25 INFO - 'PWD': '/builds/slave/test', 02:34:25 INFO - 'SHELL': '/bin/bash', 02:34:25 INFO - 'SHLVL': '1', 02:34:25 INFO - 'TERM': 'linux', 02:34:25 INFO - 'TMOUT': '86400', 02:34:25 INFO - 'USER': 'cltbld', 02:34:25 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 02:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:34:25 INFO - '_': '/tools/buildbot/bin/python'} 02:34:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:34:25 INFO - Cleaning up... 02:34:25 INFO - Return code: 0 02:34:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:34:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:34:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:34:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9efdcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed1230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb090>, '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 0x1ece9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecee40>, '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': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:34:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:34:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:34:25 INFO - Using env: {'DISPLAY': ':0', 02:34:25 INFO - 'HOME': '/home/cltbld', 02:34:25 INFO - 'LANG': 'en_US.UTF-8', 02:34:25 INFO - 'LANGUAGE': 'en_US:en', 02:34:25 INFO - 'LOGNAME': 'cltbld', 02:34:25 INFO - 'MAIL': '/var/mail/cltbld', 02:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:34:25 INFO - 'MOZ_NO_REMOTE': '1', 02:34:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:34:25 INFO - 'NO_EM_RESTART': '1', 02:34:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:34:25 INFO - 'PWD': '/builds/slave/test', 02:34:25 INFO - 'SHELL': '/bin/bash', 02:34:25 INFO - 'SHLVL': '1', 02:34:25 INFO - 'TERM': 'linux', 02:34:25 INFO - 'TMOUT': '86400', 02:34:25 INFO - 'USER': 'cltbld', 02:34:25 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 02:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:34:25 INFO - '_': '/tools/buildbot/bin/python'} 02:34:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:34:25 INFO - Cleaning up... 02:34:25 INFO - Return code: 0 02:34:25 INFO - Running post-action listener: _resource_record_post_action 02:34:25 INFO - Running post-action listener: _start_resource_monitoring 02:34:25 INFO - Starting resource monitoring. 02:34:25 INFO - [mozharness: 2016-08-30 09:34:25.514042Z] Finished create-virtualenv step (success) 02:34:25 INFO - [mozharness: 2016-08-30 09:34:25.514497Z] Running install step. 02:34:25 INFO - Running pre-action listener: _resource_record_pre_action 02:34:25 INFO - Running main action method: install 02:34:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:34:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:34:25 INFO - Reading from file tmpfile_stdout 02:34:25 INFO - Detecting whether we're running mozinstall >=1.0... 02:34:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:34:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:34:25 INFO - Reading from file tmpfile_stdout 02:34:25 INFO - Output received: 02:34:25 INFO - Usage: mozinstall [options] installer 02:34:25 INFO - Options: 02:34:25 INFO - -h, --help show this help message and exit 02:34:25 INFO - -d DEST, --destination=DEST 02:34:25 INFO - Directory to install application into. [default: 02:34:25 INFO - "/builds/slave/test"] 02:34:25 INFO - --app=APP Application being installed. [default: firefox] 02:34:25 INFO - mkdir: /builds/slave/test/build/application 02:34:25 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'] 02:34:25 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 02:34:41 INFO - Reading from file tmpfile_stdout 02:34:41 INFO - Output received: 02:34:41 INFO - /builds/slave/test/build/application/firefox/firefox 02:34:41 INFO - Running post-action listener: _resource_record_post_action 02:34:41 INFO - [mozharness: 2016-08-30 09:34:41.953661Z] Finished install step (success) 02:34:41 INFO - [mozharness: 2016-08-30 09:34:41.953944Z] Running run-tests step. 02:34:41 INFO - Running pre-action listener: _resource_record_pre_action 02:34:41 INFO - Running main action method: run_tests 02:34:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:34:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:34:41 INFO - Python 2.7.3 02:34:41 INFO - Return code: 0 02:34:41 INFO - grabbing minidump binary from tooltool 02:34: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')]} 02:34: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 0x1ecb090>, '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 0x1ece9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecee40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:34: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 02:34: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 02:34: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 02:34:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:34:42 INFO - Return code: 0 02:34:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:34:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:34:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:34:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:34:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:34:42 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/EOqEJ_imR8C_8oxNHt9dxQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:34:42 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/EOqEJ_imR8C_8oxNHt9dxQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-039 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:34:42 INFO - Using env: {'DISPLAY': ':0', 02:34:42 INFO - 'HOME': '/home/cltbld', 02:34:42 INFO - 'LANG': 'en_US.UTF-8', 02:34:42 INFO - 'LANGUAGE': 'en_US:en', 02:34:42 INFO - 'LOGNAME': 'cltbld', 02:34:42 INFO - 'MAIL': '/var/mail/cltbld', 02:34:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:34:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:34:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:34:42 INFO - 'MOZ_NO_REMOTE': '1', 02:34:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:34:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:34:42 INFO - 'NO_EM_RESTART': '1', 02:34:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:34:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:34:42 INFO - 'PWD': '/builds/slave/test', 02:34:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:34:42 INFO - 'SHELL': '/bin/bash', 02:34:42 INFO - 'SHLVL': '1', 02:34:42 INFO - 'TERM': 'linux', 02:34:42 INFO - 'TMOUT': '86400', 02:34:42 INFO - 'USER': 'cltbld', 02:34:42 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468', 02:34:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:34:42 INFO - '_': '/tools/buildbot/bin/python'} 02:34:42 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/EOqEJ_imR8C_8oxNHt9dxQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:34:42 INFO - mozversion application_buildid: 20160830010820 02:34:42 INFO - mozversion application_changeset: bc60cb47882ac16cd4bade1e2d0e2623130f2e9b 02:34:42 INFO - mozversion application_display_name: Nightly 02:34:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:34:42 INFO - mozversion application_name: Firefox 02:34:42 INFO - mozversion application_remotingname: firefox 02:34:42 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 02:34:42 INFO - mozversion application_vendor: Mozilla 02:34:42 INFO - mozversion application_version: 51.0a1 02:34:42 INFO - mozversion platform_buildid: 20160830010820 02:34:42 INFO - mozversion platform_changeset: bc60cb47882ac16cd4bade1e2d0e2623130f2e9b 02:34:42 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 02:34:42 INFO - mozversion platform_version: 51.0a1 02:34:42 INFO - using testdate: 1472549682 02:34:42 INFO - actual date: 1472549682 02:34:42 INFO - starting webserver on 'localhost:60591' 02:34:42 INFO - SUITE-START | Running 2 tests 02:34:42 INFO - TEST-START | dromaeo_css 02:34:42 INFO - operating with platform_type : linux_ 02:34:42 INFO - Initialising browser for dromaeo_css test... 02:34:42 INFO - TEST-INFO | started process 3198 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpluJjOc/profile http://localhost:60591/getInfo.html) 02:34:46 INFO - PROCESS | 3198 | __metrics Screen width/height:1600/1200 02:34:46 INFO - PROCESS | 3198 | colorDepth:24 02:34:46 INFO - PROCESS | 3198 | Browser inner width/height: 1024/697 02:34:46 INFO - PROCESS | 3198 | __metrics 02:34:47 INFO - PROCESS | 3198 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:34:47 INFO - PROCESS | 3198 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:34:48 INFO - PROCESS | 3198 | console.error: PushService: 02:34:48 INFO - PROCESS | 3198 | stateChangeProcessEnqueue: Error transitioning state 02:34:48 INFO - PROCESS | 3198 | UnknownError 02:34:48 INFO - TEST-INFO | 3198: exit 0 02:34:48 INFO - Browser initialized. 02:34:48 INFO - Running cycle 1/1 for dromaeo_css test... 02:34:48 INFO - TEST-INFO | started process 3279 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpluJjOc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:35:01 INFO - PROCESS | 3279 | RSS: Main: 192729088 02:35:01 INFO - PROCESS | 3279 | 02:35:51 INFO - PROCESS | 3279 | 1472549751894 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:38:05 INFO - PROCESS | 3279 | Cycle 1(1): loaded http://localhost:60591/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60591/tests/dromaeo/cssquery-ext.html) 02:38:06 INFO - PROCESS | 3279 | RSS: Main: 317952000 02:38:06 INFO - PROCESS | 3279 | 02:41:09 INFO - PROCESS | 3279 | Cycle 1(1): loaded http://localhost:60591/tests/dromaeo/cssquery-ext.html (next: http://localhost:60591/tests/dromaeo/cssquery-jquery.html) 02:41:10 INFO - PROCESS | 3279 | RSS: Main: 281337856 02:41:10 INFO - PROCESS | 3279 | 02:44:12 INFO - PROCESS | 3279 | Cycle 1(1): loaded http://localhost:60591/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60591/tests/dromaeo/cssquery-mootools.html) 02:44:13 INFO - PROCESS | 3279 | RSS: Main: 220905472 02:44:13 INFO - PROCESS | 3279 | 02:47:15 INFO - PROCESS | 3279 | Cycle 1(1): loaded http://localhost:60591/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60591/tests/dromaeo/cssquery-prototype.html) 02:47:16 INFO - PROCESS | 3279 | RSS: Main: 221360128 02:47:16 INFO - PROCESS | 3279 | 02:50:19 INFO - PROCESS | 3279 | Cycle 1(1): loaded http://localhost:60591/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60591/tests/dromaeo/cssquery-yui.html) 02:50:19 INFO - PROCESS | 3279 | RSS: Main: 221450240 02:50:19 INFO - PROCESS | 3279 | 02:53:23 INFO - PROCESS | 3279 | Cycle 1(1): loaded http://localhost:60591/tests/dromaeo/cssquery-yui.html (next: http://localhost:60591/tests/dromaeo/cssquery-dojo.html) 02:53:23 INFO - PROCESS | 3279 | RSS: Main: 298643456 02:53:23 INFO - PROCESS | 3279 | 02:53:23 INFO - PROCESS | 3279 | __start_tp_report 02:53:23 INFO - PROCESS | 3279 | _x_x_mozilla_page_load 02:53:23 INFO - PROCESS | 3279 | _x_x_mozilla_page_load_details 02:53:23 INFO - PROCESS | 3279 | |i|pagename|runs| 02:53:23 INFO - PROCESS | 3279 | |0;dojo.html;2637.9604305935413;2639.815212935094;2653.45604151149;2705.5671092625184;2707.6615423072117;4514.367988481616;4579.290210017444;4685.976570117149;4778.235482322829;4827.6137908967285;4609;4615.284630882219;4698.624110071195;4717.811287548494;4718.127146477904;4236.57634236577;4255.234057869582;4311.223979683656;4369.104333611602;4405.625521830632;4318.438602981623;4361.563843615629;4396.780160991953;4411;4422;4226.36604509323;4311.913761724748;4312.05134870328;4327.459067616548;4343.370211319341;4326.6754993375625;4347.739135651839;4352.8476503322545;4358.193734159191;4419.226635338835;4256.978715106436;4265.4881414230495;4276.273033584298;4378.64970802335;4388.7147335423415;4658.114958157973;4725.598324142452;4727.196376615983;4734.147853386389;4776.904461910777;4906.9263961040615;4969.403671559436;5009.273655319958;5085.466026067246;5089.516495932918;4999.450060493343;5021.246812978046;5023.447420783711;5043.394792624908;5078.289039534468;4671.0891376182;4698.788554515021;4718.386609740712;4720.645951553579;4769.42766867978;4915.311856340115;5035.093683136997;5125.077486052472;5135.614828887774;5161.5870730341485;4910.803567857317;4923.187674033816;4924.064427758714;4936.456989731127;4976.278439626234;5008.874778130503;5015.398152221684;5017.297578339036;5018.623603229772;5059.468755780664;4766.094442055998;4949.084419382426;5003.474635163256;5006.048850718425;5010.824621138243;5024.648274620815;5031.84904452867;5035.395752509722;5035.571976382049;5061.139606266944;4394.12117576486;4402.845900393535;4404.955950440519;4438.733675979452;4456.3538286948215;3729.701623870084;3781.905452363713;3784.8675296365045;3815.694744420495;3839.9424008639894;4359.280718681445;4384.605385515255;4386.8025938832825;4415.955840441618;4424.557544245549;5043.193089105799;5064.265681476165;5070.188769796815;5076.340075790197;5129.820456284012;4379.211741886491;4382.057857560576;4385.144896745168;4390.517043125254;4442.244818380883;4555.726656400627;4609.354690343354;4646.79089440976;4651.976740116278;4728.1252968201;4796;4809.110314591811;4809.495003024772;4809.903801923872;4869.756512174448;5077.974610127074;5103.081445339874;5120.4111527174855;5129.204973229155;5177.042247184283;4597.7241365518175;4693.624510039257;4723.055388922298;4729.574338309569;4809.038192361471;4952.455229924633;4970.776315065758;4972.676776009548;4977.203647416396;5041.924371134509;5029.119904016856;5045.772940217773;5046.84859454217;5049.26785616091;5105.33630628016;4798.47216806144;4843.1524483216;4849.078675051729;4914.434839993446;4916.631252655993;4878.707277563358;4888.315635810918;4907.705537667752;4959.280904268789;5026.070177017264;4849.903001939871;4899.877503062452;4905.877353066202;4955.1823949048;4963.70217786934;5001.724905130252;5007.073691367109;5008.599312054954;5013.172826483589;5072.898542029213;4681.063787242497;4777.808887644455;4836.032793441255;4846.17615005457;4860.392450943632 02:53:23 INFO - PROCESS | 3279 | |1;ext.html;560.4843543939576;577.3360635269439;580.8402689260454;583.8219343100355;591.4617697894917;28169.859150704244;37910.43134352983;39298.41052384216;39386.21227575454;43218.351724724074;39506.802465987705;39890.202195956066;40090.7995460023;40104;40111.19777604454;7287.890681639779;7656.196099409565;7801.648925798324;7801.843963120734;7809.531428114303;9648.324617276758;9873.062059104373;9921;10309.690709278733;10315.79368412635;9701.660441884536;9786.26603004774;9794;9798.608055677765;9801.41191528507;9939.55272012761;9957.302988790787;10010.799784004279;10026.649067282682;10030.699079027605;9860.260480463994;9921.454320012397;9929.602815887356;9934.205263578897;9935.701928942144;38411.615883841085;38697;38742.41886371707;38772.41841372382;38813.41779873304;2955.3202763364548;2994.2514371407146;3037.711417415342;3081.1064791210524;3085.336652619675;27807.860960695067;29023.50768740692;29440.263993400335;29477.85261073681;29701.405971880442;3260.902172934792;3261.59230096238;3261.706446419834;3277.3117645294515;3296.6373698893103;241009.79495102586;241574;241892;241961;242381;51450.228246576335;51783.74108129435;51786.22320665193;51810.22284665733;51857.48142518602;31947;32575.348493030007;32797.67202327994;34659.82670086633;34698.47952280718;14308.569143085608;14320.928395357956;14429.639259018608;14470;14496.782548261785;10251.692449226535;10568.365898045988;10712.892871071346;11126;11208.103351731841;17590.38433654816;17611.55971100733;17632;17640;17712.46862594124;6670.837181129369;6706.329367063352;6783.3555812197765;6814.591124532544;6967.477439192081;782.765170448863;813.5647428625657;883.3904605821963;889.8709687095335;926.9304802139867;25811.354716132246;27821.582676259874;27971.518892667198;28097.578536321562;28376.85811570929;944.7307517357658;989.1987490133015;994;1001.8647482589903;1018.9214003432913;13953.860461395256;14577.355661084728;14781.187034713193;14798.63003424923;15210.08739475635;375853;377261;378257;378631;378651;48382.5161748378;48820.26769598459;48874;48915.75542122267;48967.510324897004;1055.18223376882;1106.8948449897407;1148.655403378983;1170;1176.5529098942347;26464;28605.570916435852;28833.85583072155;28964;29336.413271734873;41274.38088428736;41601.99999999879;41871.1625767489;41941.161176776906;41963.74108776742;291453;293616;293704;294090;295051;22424.88787556052;22819.20132795344;22901.31296061121;22925.770742292363;24836.751632483443;41245;41281.793591031856;41288.174236515704;41309.79345103255;41436.72098034637;35293.294134117685;35401.645983539835;35550.46674299836;35556;35729.28541429105;35312.11719706925;35422;35577.28845423129;35658.28683426369;35672.821635891654;34364.31271374608;34408.655913441544;34932.82533587417;35152;35624.287514250085;29873.850630746707;32399.676003239667;32491.35017299688;32523.674763252064;32535.18662033468;24606;24791.132310369052;24835.88293482285;25131.74868251294;25257.368565786004 02:53:23 INFO - PROCESS | 3279 | |2;jquery.html;338.89494256780415;352.3077153393581;365.7512891233961;367.5644361431704;375.92672918816777;12609.747805043906;15040.623984400396;16478.34086636533;16582.585435364123;16616.750748738763;15872.920635396807;15881.126538040404;16146.273417696202;16285.51143465695;16287.104209268486;4701.318308845215;4923.753812309388;4958.677685950402;4977.751112444382;4980.526849949267;3629.530040333669;3808.8857334280015;4933.432655244639;4953.3560637117125;5016.523430274118;3819.9236015279675;3838.9424158637644;5664.575156863231;5699.116636921284;5802.65184088956;5619.823157306109;5774.133879918004;5777.653340799566;5813.6802475863815;5884.47039766423;5081.898362032775;5095.439501654815;5097.057044446789;5104.106781313292;5105.183170692709;20566.38300850977;21182;21226.469338266514;21456.35630931059;21476.24833130833;1794.605186858889;1877.3898482992945;1889.7070954002152;1900.7433996410446;1915.9329423470251;12465.563705270362;12822.166559173624;12931.224126552437;13196.472141114344;13618.20826730518;1707.2061491406555;1721.4147189955474;1725.767021452113;1742.9825701742948;1851.9444416667523;66176.33823661749;67499.3250067498;68042.65978670125;68064.6596767013;69056.6547167261;8836.602352894135;9137.543122843832;9288.535573221312;9303.395279306958;9652.179564736936;12698.746025079447;12721.173123746927;12724.745505090032;12744.872551274368;12765.872341276654;6590.209174899042;7049.118860142482;7168.605726685082;7192.604406757679;7256.818579535554;6704.698288577026;6803.965980170067;6817.5227734059135;6874.381305682413;6938.5143039986715;5784.88430231393;5884.705764711747;5892.793752218652;5965.940340596625;6175.783847565313;1605.5664970458145;1669.1153688545091;1670.599056226497;1703.4123227486498;1742.0505824325774;671.771597656799;831.1895901495993;853.8633818588996;899.6221586933503;917.7338571814099;16893.746593801105;17010.744838827428;17720.645587088187;17922;18381.356652517097;814.2752949874616;844.7719115838812;854.3122786157203;854.525738215284;882.4793371910604;9735.269854760922;10524.105451036748;10525.684229473129;10532.736681583021;10572.52423640938;362975;371690;373194;374814;376005;70046;71262.64368678123;72533;74466.62766686131;77284.22715772883;879.2877769006997;895.5029958373166;919.3334832246701;919.384012711485;923.1553128886993;15381.615459613598;15600.609984750472;16032.438864639682;16102.677946440772;16279.918600406922;35496.82251588725;36013.81993090018;36383.636163638024;36621.61959472626;38421.03947401226;48623;48851.51148488469;49174.50825491699;49631.503684962685;50009.49990500194;6712.228093769279;6896.689648965909;6942.548734332252;7296.817579560554;7619.619019049136;10052.597896084368;12170.695732606755;12174.391280435766;12387.62837114888;12875.35623218854;9945.900540994497;10063.446510441703;10119.747006324606;10446.216533759847;10983.340999539734;10317.432541210306;10566;10667.839982400425;10685.198610104118;10966.561337546409;10548.683539493828;10763.569457221623;10786.946065269623;10832.891671083189;11064.336139831636;6857.417119544894;7125.5368401053765;7210.4231661468;7259.564426134449;7290;8205.466644668077;10641.733956651145;10690.144788417063;10870.891291086988;11258.549658013588 02:53:23 INFO - PROCESS | 3279 | |3;mootools.html;1306.3990564340406;1367.2821768571498;1390.3048475762118;1620.4571468558033;1641.171208539688;7022.36798688118;7300.1604815446235;7643.388528917687;7843.607819609018;7854.939583156265;7669.923300766991;7977.282044615983;7979.646004428693;7980.082290536576;7985.800354991128;2134.6691262854283;2165;2166.6533354663256;2166.674998750192;2171.1641018208024;1848.2422206895176;2069.286096296775;2109.156337465011;2132.9040193191345;2135.284679632325;2167.696522486853;2185.1914791527156;2192.3861318830673;2195.2536137713173;2197.373748481683;2127.914883404654;2135.3807395855183;2160.1791319298723;2164.3074216250807;2166.6641670540935;2041.2141325589755;2052.220156340596;2067.214458505773;2076.7404074490687;2098.4858709616087;7917;8024.759257222293;8031.598420078973;8034;8035.477693949874;4285.692863676577;4392.736435813861;4395.824167033315;4400.383946247528;4403.955960440387;2781.4019985702967;2870.454613623405;2917.9124626261246;2935.765138788913;2970.9405811883857;893.6559424621568;933.0809152984305;944.23516951269;945.2249155692335;949.2500924269818;10270.127039201678;10281.074703276667;10282.79434411315;10285.331453455576;10290.485475726258;3676.852925882988;4016.779077150785;4120.114175452292;4124.257633625976;4141.6065473779945;8892.644294228288;8898.199162075443;8898.955505222433;8901.73294801157;8903.19871211594;4457.487388950248;4459.10817836434;4463.843765468193;4466.0844526871915;4473.46318441789;4069.7761623110423;4092.815823287959;4094.2220978014084;4095.5290141634096;4095.5904409559403;3355.9496607550905;3367.4275373186615;3376.324735052999;3388.186835159593;3447.3622379859808;939.4081728510994;1013.5742987945081;1032.3289861590026;1032.963846265377;1044.9059584637419;741.8924255982852;768.2624680306968;776.1191048160379;785.2304741353391;830.5515021888234;5057.848264552069;5083.567896728746;5117.769700363493;5139.8458046258675;5187.584993200535;763.5342441110918;802.2859654907137;851.9105493923049;878.2578720980761;882.7087061269766;3514.9121271968406;3528.9294214115575;3537.4870643756512;3546.078019714893;3599.1644261426336;9265.629374824932;9683.612655493702;9717;9731;9769.267304952156;9537.523123843781;9616.51917404127;9624.326297159272;9631.229501639851;9645.42127472354;7246.637668116573;7324.315407456208;7467.58928258951;7478;7482.738104166437;4901.2893130496595;5327.9200811987075;5366.463353664759;5473.151661492475;5670.432956704297;8902.154295341932;8924.241439477457;8955.686550970684;8959.865602016105;9210.447373157633;8784.385093043426;9180.770480738034;9243.168114869693;9351.111644393772;9370.906290937004;6561.1470508833545;6650.900236496554;6693.196816382066;6693.765718199839;6694.464442844658;7205.639718013974;7234.5659260444545;7255.709771609288;7281.344678978706;7284.053073100463;6436.259830119409;6525.575837570543;6527.640979745969;6589.24223714279;6726.428253598499;6437.324080971431;6508.316626754123;6513.185851768529;6533.379328963741;6616.66916654175;5393.6763794172475;5668.943310566841;5706.144078388275;5708.229389032427;5709.200711900255;4223;4331.068820203672;4348.108637729195;4362.803673834622;4462.308342206963;4311.698181127291;4392.05570802279;4463.732176069446;4505.459344878635;4553.3397657340165 02:53:23 INFO - PROCESS | 3279 | |4;prototype.html;296.26082923106844;297.67850721221083;300.45467476530104;305.2049411283605;308.48483033334327;4744.833930812422;4780.545848144425;4780.545848144425;4784.808607655689;4821.349117869091;4715.78778954947;4729;4740.246108939688;4749.833755818547;4763.928541071887;3597.550306211717;3644.890653280406;3754.6996240300846;3791.791451470168;3801.9619803802025;3757.9436308455397;3779.3197224499577;3779.8677046303383;3788.488554045196;3803.1633040730994;3716.256748650267;3724.944125838115;3737.6449237322413;3739.7943113128767;3762.473253744478;3501.124718820295;3590.8922732318074;3705.166337574024;3718.8698395556025;3759.5676497202626;3347.966520334814;3354.8490317935753;3360.294338188983;3363.7477189210663;3363.7813542119684;4632.0967411354795;4662.790174442158;4672.345871577982;4672.742999135046;4683.414573178353;4220.767857767822;4294.141171765628;4326.978365108186;4327.415798867144;4338.566143385668;4904.509549045067;4930.939847932712;4933.7286449245275;4957.231629097496;4970.751462426864;3661.9816900915325;3662.8351724172476;3669.321175582499;3694.5935947045805;3708.2583483303447;5053.873653158664;5123.487651234884;5154.742262886878;5211.472435868601;5272.156454967187;3700.703943684499;3714.257148570297;3725.441183822394;3730.477733117366;3751.643593858579;3765.9623403765613;3770.75639994203;3790.905227369338;3809.466674665549;3821.197548514843;4261;4292.7424354538825;4294.484661840599;4297.269464191095;4307.806148723315;4889.608831293488;4925.75371231437;4926.3842019747535;4934.53121953421;5047.899042019212;1285.8649841766667;1294.3334182895817;1296.6045356166271;1302.5961951794784;1304.8042793580848;807.6527093349798;835.1940377535586;862.344618090256;864.8140649760331;867.280157469299;4269.356297825359;4413.955860441354;4425.300566654339;4445.088756804847;4453.755043472575;5045.369328833895;5097.133487307166;5108.5951363374625;5112.923306150411;5132.769025393866;4416.734995900256;4428.955710442919;4444.200098959926;4446.333050042519;4464.749870036395;2571.3443072016516;2713.1046754570943;2713.959290610603;2714.9728502715116;2725.2233113562934;4797.960866631667;4814.903701926012;4824.6863953842885;4825.855224343275;4869.342638743795;5004.523665518681;5214.921776173284;5223.373195216523;5227.60792940531;5235.931806936239;4766.713997160112;4778.402699662543;4798.568128868418;4809.591184749335;4832.275158726219;5109.335786347751;5116.360454943132;5121.283020377075;5132.2814805926455;5132.820351287687;4816.373871396696;5051.595872330131;5053;5063.291139240436;5066;4839.346688197048;4854.975725121351;4870.537298956593;4875.9512404875495;4885.1206782779445;3642.5446819147605;3653.8173091345857;3676.632336766302;3692.5753538343433;3694.408894576855;5026.874328141826;5205.401378841481;5212.791488340576;5231.686098834082;5247.527722504993;4959.652824302264;4966.279889416087;4978.502149784992;5006.5994720423005;5011.373578302712;4945.873274492003;4953.554180123806;4978.60171186297;4983.626228032839;5001.824936127218;4660.254359302496;4912.361393019028;4926.778294976663;4954.182559877724;4961.70229786214;4727.125481785881;4889.951100488949;4973.900521989613;4980.128477516492;4980.576650984562;4858.319835223142;4859.222524396079;4866.416030076271;4872.658913875995;4888.242322440027 02:53:23 INFO - PROCESS | 3279 | |5;yui.html;596.692703257822;598.5959477352786;611.9326874043852;656.4092316914779;686.9793906182813;11535.769284614302;12434.316112613826;12437.68905777356;12457.563985260518;12581.24512529249;12649.747005059893;12746.68133296668;12759.425825837836;12772.489100435994;12825.03812214083;604;704.5737328916009;727.2799928071224;730.8319086610082;742.5025233093841;725.5791640848322;726.0053726394847;726.7165805335924;727.8980942668031;729.5513259345491;754.1063839350387;755.0863455219192;755.0976582983337;755.1391413788285;755.7052749427728;625.6746491824272;751.9022527071501;756.1719916691214;775.1783109903521;779.3998621061751;565.9377468478506;566.6458463460337;566.6458463460337;566.9121286200645;567.0898208375539;11710.063194944383;11715.062794976382;11720.589779357766;11734.47194876224;11738;10925.562977480971;11319.434028298634;11653.30080195191;11703.2978021319;12301.446434910362;10686.786264274671;10718.517666705016;10726.570937162503;10796.406197659126;10873.67378978624;3856.6529012388737;3881.126746482047;3894.941575876364;3901.7854018028997;4190.958090419118;63127;63669.681651591905;64924.350756492306;65814.67092664554;66548.3345166547;9063.773405664779;9194.678186263449;9373.156415922464;9397.060293970548;9446.38598491096;16810.91594542044;17023.148842558072;17037;17042.31830726782;17057.147142642818;5928.525717942554;6074.726637301332;6095.238095238095;6209.416598767376;6232.345603711545;3482.3209474152804;3575.284943011408;3579.874704385334;3615.313090512794;3707.6848467879995;2716.375233696261;2813.6482939632547;2814.5356016257497;2817.29567608098;2825.5620378841313;474.44016061048296;476.9332293478916;480.97595120244546;484.1285685765608;490.22054932657244;445.45431845988406;448.43497193536376;450.3020318506302;495.36098433021;566.5382713088819;7656.425768067417;7795.688172473151;7849.332806711379;8113.716019939392;8337.124601916832;464.53778490402266;468.72111093899076;520.2872065270603;546.5490969949807;566.6118708684564;3488.075659950115;3509.964900351015;3699.5375578052744;3827.770333779926;3985.1631157456677;52489.302995161415;84491.57754211313;87574;87870.56064719635;89057.55471222602;64883;66092;66645;77178;78687.60656196797;64695;66110.33889661042;66162.33837661658;67367.32632673807;67496.66251668711;7694.192109828387;7940.28537431634;7943.523388596703;8052.758417247492;8163.510189388418;42221;42986;43021.35467968046;43345.78327108344;44229.55770442254;53639.463605363446;55351;60777;61584.38415615966;63075;10342.172626190037;10557.313774604627;10725.89274107249;10791.568337266735;11351.205415621158;11823.113266504875;12052.216605920587;12414.255144690986;12779.61661150167;13167.934160329138;10329.638462653771;10480.213983951387;10614.469276535989;11185.496652650487;11199.72000699989;10102.848457273;11165.776684466102;11225.270357426742;11588.594399195988;11844.28934263947;10985.505652245827;11163.553457861595;11527.365994870363;11905.285682859056;11907.226030308002;8321.916780832114;8519.762490253912;8669.91330086691;8838.762911194808;8936.553172341486;8902.287816974496;9311.301652375963;9471;9536.133784590827;9685 02:53:23 INFO - PROCESS | 3279 | __end_tp_report 02:53:23 INFO - PROCESS | 3279 | __start_cc_report 02:53:23 INFO - PROCESS | 3279 | _x_x_mozilla_cycle_collect,1227 02:53:23 INFO - PROCESS | 3279 | __end_cc_report 02:53:23 INFO - PROCESS | 3279 | __startTimestamp1472550803282__endTimestamp 02:53:23 INFO - PROCESS | 3279 | 02:53:23 INFO - PROCESS | 3279 | ------- Summary: start ------- 02:53:23 INFO - PROCESS | 3279 | Number of tests: 6 02:53:23 INFO - PROCESS | 3279 | 02:53:23 INFO - PROCESS | 3279 | [#0] dojo.html Cycles:165 Average:4670.70 Median:4798.47 stddev:475.32 (9.9%) stddev-sans-first:449.23 02:53:23 INFO - PROCESS | 3279 | Values: 2638.0 2639.8 2653.5 2705.6 2707.7 4514.4 4579.3 4686.0 4778.2 4827.6 4609.0 4615.3 4698.6 4717.8 4718.1 4236.6 4255.2 4311.2 4369.1 4405.6 4318.4 4361.6 4396.8 4411.0 4422.0 4226.4 4311.9 4312.1 4327.5 4343.4 4326.7 4347.7 4352.8 4358.2 4419.2 4257.0 4265.5 4276.3 4378.6 4388.7 4658.1 4725.6 4727.2 4734.1 4776.9 4906.9 4969.4 5009.3 5085.5 5089.5 4999.5 5021.2 5023.4 5043.4 5078.3 4671.1 4698.8 4718.4 4720.6 4769.4 4915.3 5035.1 5125.1 5135.6 5161.6 4910.8 4923.2 4924.1 4936.5 4976.3 5008.9 5015.4 5017.3 5018.6 5059.5 4766.1 4949.1 5003.5 5006.0 5010.8 5024.6 5031.8 5035.4 5035.6 5061.1 4394.1 4402.8 4405.0 4438.7 4456.4 3729.7 3781.9 3784.9 3815.7 3839.9 4359.3 4384.6 4386.8 4416.0 4424.6 5043.2 5064.3 5070.2 5076.3 5129.8 4379.2 4382.1 4385.1 4390.5 4442.2 4555.7 4609.4 4646.8 4652.0 4728.1 4796.0 4809.1 4809.5 4809.9 4869.8 5078.0 5103.1 5120.4 5129.2 5177.0 4597.7 4693.6 4723.1 4729.6 4809.0 4952.5 4970.8 4972.7 4977.2 5041.9 5029.1 5045.8 5046.8 5049.3 5105.3 4798.5 4843.2 4849.1 4914.4 4916.6 4878.7 4888.3 4907.7 4959.3 5026.1 4849.9 4899.9 4905.9 4955.2 4963.7 5001.7 5007.1 5008.6 5013.2 5072.9 4681.1 4777.8 4836.0 4846.2 4860.4 02:53:23 INFO - PROCESS | 3279 | 02:53:23 INFO - PROCESS | 3279 | [#1] ext.html Cycles:180 Average:45523.86 Median:35042.41 stddev:81292.15 (232.0%) stddev-sans-first:81450.09 02:53:23 INFO - PROCESS | 3279 | Values: 560.5 577.3 580.8 583.8 591.5 28169.9 37910.4 39298.4 39386.2 43218.4 39506.8 39890.2 40090.8 40104.0 40111.2 7287.9 7656.2 7801.6 7801.8 7809.5 9648.3 9873.1 9921.0 10309.7 10315.8 9701.7 9786.3 9794.0 9798.6 9801.4 9939.6 9957.3 10010.8 10026.6 10030.7 9860.3 9921.5 9929.6 9934.2 9935.7 38411.6 38697.0 38742.4 38772.4 38813.4 2955.3 2994.3 3037.7 3081.1 3085.3 27807.9 29023.5 29440.3 29477.9 29701.4 3260.9 3261.6 3261.7 3277.3 3296.6 241009.8 241574.0 241892.0 241961.0 242381.0 51450.2 51783.7 51786.2 51810.2 51857.5 31947.0 32575.3 32797.7 34659.8 34698.5 14308.6 14320.9 14429.6 14470.0 14496.8 10251.7 10568.4 10712.9 11126.0 11208.1 17590.4 17611.6 17632.0 17640.0 17712.5 6670.8 6706.3 6783.4 6814.6 6967.5 782.8 813.6 883.4 889.9 926.9 25811.4 27821.6 27971.5 28097.6 28376.9 944.7 989.2 994.0 1001.9 1018.9 13953.9 14577.4 14781.2 14798.6 15210.1 375853.0 377261.0 378257.0 378631.0 378651.0 48382.5 48820.3 48874.0 48915.8 48967.5 1055.2 1106.9 1148.7 1170.0 1176.6 26464.0 28605.6 28833.9 28964.0 29336.4 41274.4 41602.0 41871.2 41941.2 41963.7 291453.0 293616.0 293704.0 294090.0 295051.0 22424.9 22819.2 22901.3 22925.8 24836.8 41245.0 41281.8 41288.2 41309.8 41436.7 35293.3 35401.6 35550.5 35556.0 35729.3 35312.1 35422.0 35577.3 35658.3 35672.8 34364.3 34408.7 34932.8 35152.0 35624.3 29873.9 32399.7 32491.4 32523.7 32535.2 24606.0 24791.1 24835.9 25131.7 25257.4 02:53:23 INFO - PROCESS | 3279 | 02:53:23 INFO - PROCESS | 3279 | [#2] jquery.html Cycles:180 Average:23655.05 Median:188190.46 stddev:61356.83 (32.6%) stddev-sans-first:61503.98 02:53:23 INFO - PROCESS | 3279 | Values: 338.9 352.3 365.8 367.6 375.9 12609.7 15040.6 16478.3 16582.6 16616.8 15872.9 15881.1 16146.3 16285.5 16287.1 4701.3 4923.8 4958.7 4977.8 4980.5 3629.5 3808.9 4933.4 4953.4 5016.5 3819.9 3838.9 5664.6 5699.1 5802.7 5619.8 5774.1 5777.7 5813.7 5884.5 5081.9 5095.4 5097.1 5104.1 5105.2 20566.4 21182.0 21226.5 21456.4 21476.2 1794.6 1877.4 1889.7 1900.7 1915.9 12465.6 12822.2 12931.2 13196.5 13618.2 1707.2 1721.4 1725.8 1743.0 1851.9 66176.3 67499.3 68042.7 68064.7 69056.7 8836.6 9137.5 9288.5 9303.4 9652.2 12698.7 12721.2 12724.7 12744.9 12765.9 6590.2 7049.1 7168.6 7192.6 7256.8 6704.7 6804.0 6817.5 6874.4 6938.5 5784.9 5884.7 5892.8 5965.9 6175.8 1605.6 1669.1 1670.6 1703.4 1742.1 671.8 831.2 853.9 899.6 917.7 16893.7 17010.7 17720.6 17922.0 18381.4 814.3 844.8 854.3 854.5 882.5 9735.3 10524.1 10525.7 10532.7 10572.5 362975.0 371690.0 373194.0 374814.0 376005.0 70046.0 71262.6 72533.0 74466.6 77284.2 879.3 895.5 919.3 919.4 923.2 15381.6 15600.6 16032.4 16102.7 16279.9 35496.8 36013.8 36383.6 36621.6 38421.0 48623.0 48851.5 49174.5 49631.5 50009.5 6712.2 6896.7 6942.5 7296.8 7619.6 10052.6 12170.7 12174.4 12387.6 12875.4 9945.9 10063.4 10119.7 10446.2 10983.3 10317.4 10566.0 10667.8 10685.2 10966.6 10548.7 10763.6 10786.9 10832.9 11064.3 6857.4 7125.5 7210.4 7259.6 7290.0 8205.5 10641.7 10690.1 10870.9 11258.5 02:53:23 INFO - PROCESS | 3279 | 02:53:23 INFO - PROCESS | 3279 | [#3] mootools.html Cycles:180 Average:5049.27 Median:5347.19 stddev:2876.61 (53.8%) stddev-sans-first:2870.93 02:53:23 INFO - PROCESS | 3279 | Values: 1306.4 1367.3 1390.3 1620.5 1641.2 7022.4 7300.2 7643.4 7843.6 7854.9 7669.9 7977.3 7979.6 7980.1 7985.8 2134.7 2165.0 2166.7 2166.7 2171.2 1848.2 2069.3 2109.2 2132.9 2135.3 2167.7 2185.2 2192.4 2195.3 2197.4 2127.9 2135.4 2160.2 2164.3 2166.7 2041.2 2052.2 2067.2 2076.7 2098.5 7917.0 8024.8 8031.6 8034.0 8035.5 4285.7 4392.7 4395.8 4400.4 4404.0 2781.4 2870.5 2917.9 2935.8 2970.9 893.7 933.1 944.2 945.2 949.3 10270.1 10281.1 10282.8 10285.3 10290.5 3676.9 4016.8 4120.1 4124.3 4141.6 8892.6 8898.2 8899.0 8901.7 8903.2 4457.5 4459.1 4463.8 4466.1 4473.5 4069.8 4092.8 4094.2 4095.5 4095.6 3355.9 3367.4 3376.3 3388.2 3447.4 939.4 1013.6 1032.3 1033.0 1044.9 741.9 768.3 776.1 785.2 830.6 5057.8 5083.6 5117.8 5139.8 5187.6 763.5 802.3 851.9 878.3 882.7 3514.9 3528.9 3537.5 3546.1 3599.2 9265.6 9683.6 9717.0 9731.0 9769.3 9537.5 9616.5 9624.3 9631.2 9645.4 7246.6 7324.3 7467.6 7478.0 7482.7 4901.3 5327.9 5366.5 5473.2 5670.4 8902.2 8924.2 8955.7 8959.9 9210.4 8784.4 9180.8 9243.2 9351.1 9370.9 6561.1 6650.9 6693.2 6693.8 6694.5 7205.6 7234.6 7255.7 7281.3 7284.1 6436.3 6525.6 6527.6 6589.2 6726.4 6437.3 6508.3 6513.2 6533.4 6616.7 5393.7 5668.9 5706.1 5708.2 5709.2 4223.0 4331.1 4348.1 4362.8 4462.3 4311.7 4392.1 4463.7 4505.5 4553.3 02:53:23 INFO - PROCESS | 3279 | 02:53:23 INFO - PROCESS | 3279 | [#4] prototype.html Cycles:180 Average:4156.25 Median:4747.33 stddev:1191.29 (25.1%) stddev-sans-first:1158.86 02:53:23 INFO - PROCESS | 3279 | Values: 296.3 297.7 300.5 305.2 308.5 4744.8 4780.5 4780.5 4784.8 4821.3 4715.8 4729.0 4740.2 4749.8 4763.9 3597.6 3644.9 3754.7 3791.8 3802.0 3757.9 3779.3 3779.9 3788.5 3803.2 3716.3 3724.9 3737.6 3739.8 3762.5 3501.1 3590.9 3705.2 3718.9 3759.6 3348.0 3354.8 3360.3 3363.7 3363.8 4632.1 4662.8 4672.3 4672.7 4683.4 4220.8 4294.1 4327.0 4327.4 4338.6 4904.5 4930.9 4933.7 4957.2 4970.8 3662.0 3662.8 3669.3 3694.6 3708.3 5053.9 5123.5 5154.7 5211.5 5272.2 3700.7 3714.3 3725.4 3730.5 3751.6 3766.0 3770.8 3790.9 3809.5 3821.2 4261.0 4292.7 4294.5 4297.3 4307.8 4889.6 4925.8 4926.4 4934.5 5047.9 1285.9 1294.3 1296.6 1302.6 1304.8 807.7 835.2 862.3 864.8 867.3 4269.4 4414.0 4425.3 4445.1 4453.8 5045.4 5097.1 5108.6 5112.9 5132.8 4416.7 4429.0 4444.2 4446.3 4464.7 2571.3 2713.1 2714.0 2715.0 2725.2 4798.0 4814.9 4824.7 4825.9 4869.3 5004.5 5214.9 5223.4 5227.6 5235.9 4766.7 4778.4 4798.6 4809.6 4832.3 5109.3 5116.4 5121.3 5132.3 5132.8 4816.4 5051.6 5053.0 5063.3 5066.0 4839.3 4855.0 4870.5 4876.0 4885.1 3642.5 3653.8 3676.6 3692.6 3694.4 5026.9 5205.4 5212.8 5231.7 5247.5 4959.7 4966.3 4978.5 5006.6 5011.4 4945.9 4953.6 4978.6 4983.6 5001.8 4660.3 4912.4 4926.8 4954.2 4961.7 4727.1 4890.0 4973.9 4980.1 4980.6 4858.3 4859.2 4866.4 4872.7 4888.2 02:53:23 INFO - PROCESS | 3279 | 02:53:23 INFO - PROCESS | 3279 | [#5] yui.html Cycles:180 Average:16276.38 Median:507.82 stddev:22543.72 (4439.3%) stddev-sans-first:22576.21 02:53:23 INFO - PROCESS | 3279 | Values: 596.7 598.6 611.9 656.4 687.0 11535.8 12434.3 12437.7 12457.6 12581.2 12649.7 12746.7 12759.4 12772.5 12825.0 604.0 704.6 727.3 730.8 742.5 725.6 726.0 726.7 727.9 729.6 754.1 755.1 755.1 755.1 755.7 625.7 751.9 756.2 775.2 779.4 565.9 566.6 566.6 566.9 567.1 11710.1 11715.1 11720.6 11734.5 11738.0 10925.6 11319.4 11653.3 11703.3 12301.4 10686.8 10718.5 10726.6 10796.4 10873.7 3856.7 3881.1 3894.9 3901.8 4191.0 63127.0 63669.7 64924.4 65814.7 66548.3 9063.8 9194.7 9373.2 9397.1 9446.4 16810.9 17023.1 17037.0 17042.3 17057.1 5928.5 6074.7 6095.2 6209.4 6232.3 3482.3 3575.3 3579.9 3615.3 3707.7 2716.4 2813.6 2814.5 2817.3 2825.6 474.4 476.9 481.0 484.1 490.2 445.5 448.4 450.3 495.4 566.5 7656.4 7795.7 7849.3 8113.7 8337.1 464.5 468.7 520.3 546.5 566.6 3488.1 3510.0 3699.5 3827.8 3985.2 52489.3 84491.6 87574.0 87870.6 89057.6 64883.0 66092.0 66645.0 77178.0 78687.6 64695.0 66110.3 66162.3 67367.3 67496.7 7694.2 7940.3 7943.5 8052.8 8163.5 42221.0 42986.0 43021.4 43345.8 44229.6 53639.5 55351.0 60777.0 61584.4 63075.0 10342.2 10557.3 10725.9 10791.6 11351.2 11823.1 12052.2 12414.3 12779.6 13167.9 10329.6 10480.2 10614.5 11185.5 11199.7 10102.8 11165.8 11225.3 11588.6 11844.3 10985.5 11163.6 11527.4 11905.3 11907.2 8321.9 8519.8 8669.9 8838.8 8936.6 8902.3 9311.3 9471.0 9536.1 9685.0 02:53:23 INFO - PROCESS | 3279 | -------- Summary: end -------- 02:53:23 INFO - PROCESS | 3279 | 02:53:24 INFO - TEST-INFO | 3279: exit 0 02:53:24 INFO - TEST-OK | dromaeo_css | took 1122045ms 02:53:24 INFO - TEST-START | kraken 02:53:24 INFO - operating with platform_type : linux_ 02:53:24 INFO - Initialising browser for kraken test... 02:53:24 INFO - TEST-INFO | started process 3584 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIfeWQz/profile http://localhost:60591/getInfo.html) 02:53:28 INFO - PROCESS | 3584 | __metrics Screen width/height:1600/1200 02:53:28 INFO - PROCESS | 3584 | colorDepth:24 02:53:28 INFO - PROCESS | 3584 | Browser inner width/height: 1024/697 02:53:28 INFO - PROCESS | 3584 | __metrics 02:53:29 INFO - PROCESS | 3584 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:53:29 INFO - PROCESS | 3584 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:53:30 INFO - PROCESS | 3584 | console.error: PushService: 02:53:30 INFO - PROCESS | 3584 | stateChangeProcessEnqueue: Error transitioning state 02:53:30 INFO - PROCESS | 3584 | UnknownError 02:53:30 INFO - TEST-INFO | 3584: exit 0 02:53:30 INFO - Browser initialized. 02:53:30 INFO - Running cycle 1/1 for kraken test... 02:53:30 INFO - TEST-INFO | started process 3668 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIfeWQz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:53:43 INFO - PROCESS | 3668 | RSS: Main: 192393216 02:53:43 INFO - PROCESS | 3668 | 02:53:45 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:60591/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60591/tests/kraken/driver.html?testName=audio-beat-detection) 02:53:46 INFO - PROCESS | 3668 | RSS: Main: 237182976 02:53:46 INFO - PROCESS | 3668 | 02:53:49 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:60591/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60591/tests/kraken/driver.html?testName=audio-dft) 02:53:50 INFO - PROCESS | 3668 | RSS: Main: 228171776 02:53:50 INFO - PROCESS | 3668 | 02:53:53 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:60591/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60591/tests/kraken/driver.html?testName=audio-fft) 02:53:54 INFO - PROCESS | 3668 | RSS: Main: 307466240 02:53:54 INFO - PROCESS | 3668 | 02:53:56 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:60591/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60591/tests/kraken/driver.html?testName=audio-oscillator) 02:53:56 INFO - PROCESS | 3668 | RSS: Main: 216096768 02:53:56 INFO - PROCESS | 3668 | 02:53:59 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:60591/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60591/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:53:59 INFO - PROCESS | 3668 | RSS: Main: 218316800 02:53:59 INFO - PROCESS | 3668 | 02:54:02 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:60591/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60591/tests/kraken/driver.html?testName=imaging-darkroom) 02:54:03 INFO - PROCESS | 3668 | RSS: Main: 257777664 02:54:03 INFO - PROCESS | 3668 | 02:54:07 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:60591/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60591/tests/kraken/driver.html?testName=imaging-desaturate) 02:54:07 INFO - PROCESS | 3668 | RSS: Main: 251736064 02:54:07 INFO - PROCESS | 3668 | 02:54:10 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:60591/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60591/tests/kraken/driver.html?testName=json-parse-financial) 02:54:11 INFO - PROCESS | 3668 | RSS: Main: 255037440 02:54:11 INFO - PROCESS | 3668 | 02:54:12 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:60591/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60591/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:54:13 INFO - PROCESS | 3668 | RSS: Main: 218816512 02:54:13 INFO - PROCESS | 3668 | 02:54:15 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:60591/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60591/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:54:16 INFO - PROCESS | 3668 | RSS: Main: 226394112 02:54:16 INFO - PROCESS | 3668 | 02:54:18 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:60591/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60591/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:54:18 INFO - PROCESS | 3668 | RSS: Main: 224612352 02:54:18 INFO - PROCESS | 3668 | 02:54:21 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:60591/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60591/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:54:22 INFO - PROCESS | 3668 | RSS: Main: 226660352 02:54:22 INFO - PROCESS | 3668 | 02:54:25 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:60591/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60591/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:54:25 INFO - PROCESS | 3668 | RSS: Main: 224182272 02:54:25 INFO - PROCESS | 3668 | 02:54:27 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:60591/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60591/tests/kraken/driver.html?testName=ai-astar) 02:54:28 INFO - PROCESS | 3668 | RSS: Main: 274558976 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | __start_tp_report 02:54:28 INFO - PROCESS | 3668 | _x_x_mozilla_page_load 02:54:28 INFO - PROCESS | 3668 | _x_x_mozilla_page_load_details 02:54:28 INFO - PROCESS | 3668 | |i|pagename|runs| 02:54:28 INFO - PROCESS | 3668 | |0;ai-astar;115;114;114;114;116;115;117;107;115;114 02:54:28 INFO - PROCESS | 3668 | |1;audio-beat-detection;154;130;151;193;134;157;155;137;185;123 02:54:28 INFO - PROCESS | 3668 | |2;audio-dft;165;166;163;164;163;164;165;164;164;163 02:54:28 INFO - PROCESS | 3668 | |3;audio-fft;76;76;77;176;76;77;76;77;172;75 02:54:28 INFO - PROCESS | 3668 | |4;audio-oscillator;85;84;141;84;83;83;146;83;82;144 02:54:28 INFO - PROCESS | 3668 | |5;imaging-gaussian-blur;117;117;118;117;117;117;117;117;118;119 02:54:28 INFO - PROCESS | 3668 | |6;imaging-darkroom;146;148;146;147;146;147;148;147;147;147 02:54:28 INFO - PROCESS | 3668 | |7;imaging-desaturate;95;95;94;95;95;95;95;94;94;94 02:54:28 INFO - PROCESS | 3668 | |8;json-parse-financial;66;65;67;64;65;66;64;64;64;64 02:54:28 INFO - PROCESS | 3668 | |9;json-stringify-tinderbox;65;65;65;66;67;66;67;69;65;66 02:54:28 INFO - PROCESS | 3668 | |10;stanford-crypto-aes;83;84;83;83;91;86;82;85;83;83 02:54:28 INFO - PROCESS | 3668 | |11;stanford-crypto-ccm;141;133;133;136;138;135;135;144;179;133 02:54:28 INFO - PROCESS | 3668 | |12;stanford-crypto-pbkdf2;176;178;179;174;184;176;179;176;179;175 02:54:28 INFO - PROCESS | 3668 | |13;stanford-crypto-sha256-iterative;70;73;70;69;70;69;69;69;68;72 02:54:28 INFO - PROCESS | 3668 | __end_tp_report 02:54:28 INFO - PROCESS | 3668 | __start_cc_report 02:54:28 INFO - PROCESS | 3668 | _x_x_mozilla_cycle_collect,2004 02:54:28 INFO - PROCESS | 3668 | __end_cc_report 02:54:28 INFO - PROCESS | 3668 | __startTimestamp1472550868027__endTimestamp 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | ------- Summary: start ------- 02:54:28 INFO - PROCESS | 3668 | Number of tests: 14 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.00 stddev:2.69 (2.3%) stddev-sans-first:2.83 02:54:28 INFO - PROCESS | 3668 | Values: 115.0 114.0 114.0 114.0 116.0 115.0 117.0 107.0 115.0 114.0 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | [#1] audio-beat-detection Cycles:10 Average:151.90 Median:154.50 stddev:22.81 (14.8%) stddev-sans-first:24.18 02:54:28 INFO - PROCESS | 3668 | Values: 154.0 130.0 151.0 193.0 134.0 157.0 155.0 137.0 185.0 123.0 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 02:54:28 INFO - PROCESS | 3668 | Values: 165.0 166.0 163.0 164.0 163.0 164.0 165.0 164.0 164.0 163.0 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | [#3] audio-fft Cycles:10 Average:95.80 Median:76.00 stddev:41.23 (54.3%) stddev-sans-first:43.10 02:54:28 INFO - PROCESS | 3668 | Values: 76.0 76.0 77.0 176.0 76.0 77.0 76.0 77.0 172.0 75.0 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | [#4] audio-oscillator Cycles:10 Average:101.50 Median:83.00 stddev:29.13 (35.1%) stddev-sans-first:30.28 02:54:28 INFO - PROCESS | 3668 | Values: 85.0 84.0 141.0 84.0 83.0 83.0 146.0 83.0 82.0 144.0 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 02:54:28 INFO - PROCESS | 3668 | Values: 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 118.0 119.0 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 02:54:28 INFO - PROCESS | 3668 | Values: 146.0 148.0 146.0 147.0 146.0 147.0 148.0 147.0 147.0 147.0 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 02:54:28 INFO - PROCESS | 3668 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 95.0 94.0 94.0 94.0 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:65.00 stddev:1.10 (1.7%) stddev-sans-first:1.09 02:54:28 INFO - PROCESS | 3668 | Values: 66.0 65.0 67.0 64.0 65.0 66.0 64.0 64.0 64.0 64.0 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:1.29 (1.9%) stddev-sans-first:1.30 02:54:28 INFO - PROCESS | 3668 | Values: 65.0 65.0 65.0 66.0 67.0 66.0 67.0 69.0 65.0 66.0 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:83.50 stddev:2.63 (3.1%) stddev-sans-first:2.74 02:54:28 INFO - PROCESS | 3668 | Values: 83.0 84.0 83.0 83.0 91.0 86.0 82.0 85.0 83.0 83.0 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | [#11] stanford-crypto-ccm Cycles:10 Average:140.70 Median:137.00 stddev:13.94 (10.2%) stddev-sans-first:14.79 02:54:28 INFO - PROCESS | 3668 | Values: 141.0 133.0 133.0 136.0 138.0 135.0 135.0 144.0 179.0 133.0 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:178.50 stddev:2.88 (1.6%) stddev-sans-first:2.99 02:54:28 INFO - PROCESS | 3668 | Values: 176.0 178.0 179.0 174.0 184.0 176.0 179.0 176.0 179.0 175.0 02:54:28 INFO - PROCESS | 3668 | 02:54:28 INFO - PROCESS | 3668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:70.00 stddev:1.52 (2.2%) stddev-sans-first:1.62 02:54:28 INFO - PROCESS | 3668 | Values: 70.0 73.0 70.0 69.0 70.0 69.0 69.0 69.0 68.0 72.0 02:54:28 INFO - PROCESS | 3668 | -------- Summary: end -------- 02:54:28 INFO - PROCESS | 3668 | 02:54:29 INFO - TEST-INFO | 3668: exit 0 02:54:29 INFO - TEST-OK | kraken | took 65085ms 02:54:29 INFO - SUITE-END | took 1187s 02:54:29 INFO - Completed test suite (00:19:47) 02:54:29 INFO - javascript benchmark 02:54:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2637.9604305935413, 2639.815212935094, 2653.45604151149, 2705.5671092625184, 2707.6615423072117, 4514.367988481616, 4579.290210017444, 4685.976570117149, 4778.235482322829, 4827.6137908967285, 4609.0, 4615.284630882219, 4698.624110071195, 4717.811287548494, 4718.127146477904, 4236.57634236577, 4255.234057869582, 4311.223979683656, 4369.104333611602, 4405.625521830632, 4318.438602981623, 4361.563843615629, 4396.780160991953, 4411.0, 4422.0, 4226.36604509323, 4311.913761724748, 4312.05134870328, 4327.459067616548, 4343.370211319341, 4326.6754993375625, 4347.739135651839, 4352.8476503322545, 4358.193734159191, 4419.226635338835, 4256.978715106436, 4265.4881414230495, 4276.273033584298, 4378.64970802335, 4388.7147335423415, 4658.114958157973, 4725.598324142452, 4727.196376615983, 4734.147853386389, 4776.904461910777, 4906.9263961040615, 4969.403671559436, 5009.273655319958, 5085.466026067246, 5089.516495932918, 4999.450060493343, 5021.246812978046, 5023.447420783711, 5043.394792624908, 5078.289039534468, 4671.0891376182, 4698.788554515021, 4718.386609740712, 4720.645951553579, 4769.42766867978, 4915.311856340115, 5035.093683136997, 5125.077486052472, 5135.614828887774, 5161.5870730341485, 4910.803567857317, 4923.187674033816, 4924.064427758714, 4936.456989731127, 4976.278439626234, 5008.874778130503, 5015.398152221684, 5017.297578339036, 5018.623603229772, 5059.468755780664, 4766.094442055998, 4949.084419382426, 5003.474635163256, 5006.048850718425, 5010.824621138243, 5024.648274620815, 5031.84904452867, 5035.395752509722, 5035.571976382049, 5061.139606266944, 4394.12117576486, 4402.845900393535, 4404.955950440519, 4438.733675979452, 4456.3538286948215, 3729.701623870084, 3781.905452363713, 3784.8675296365045, 3815.694744420495, 3839.9424008639894, 4359.280718681445, 4384.605385515255, 4386.8025938832825, 4415.955840441618, 4424.557544245549, 5043.193089105799, 5064.265681476165, 5070.188769796815, 5076.340075790197, 5129.820456284012, 4379.211741886491, 4382.057857560576, 4385.144896745168, 4390.517043125254, 4442.244818380883, 4555.726656400627, 4609.354690343354, 4646.79089440976, 4651.976740116278, 4728.1252968201, 4796.0, 4809.110314591811, 4809.495003024772, 4809.903801923872, 4869.756512174448, 5077.974610127074, 5103.081445339874, 5120.4111527174855, 5129.204973229155, 5177.042247184283, 4597.7241365518175, 4693.624510039257, 4723.055388922298, 4729.574338309569, 4809.038192361471, 4952.455229924633, 4970.776315065758, 4972.676776009548, 4977.203647416396, 5041.924371134509, 5029.119904016856, 5045.772940217773, 5046.84859454217, 5049.26785616091, 5105.33630628016, 4798.47216806144, 4843.1524483216, 4849.078675051729, 4914.434839993446, 4916.631252655993, 4878.707277563358, 4888.315635810918, 4907.705537667752, 4959.280904268789, 5026.070177017264, 4849.903001939871, 4899.877503062452, 4905.877353066202, 4955.1823949048, 4963.70217786934, 5001.724905130252, 5007.073691367109, 5008.599312054954, 5013.172826483589, 5072.898542029213, 4681.063787242497, 4777.808887644455, 4836.032793441255, 4846.17615005457, 4860.392450943632], "value": 4641.255267481128, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.4843543939576, 577.3360635269439, 580.8402689260454, 583.8219343100355, 591.4617697894917, 28169.859150704244, 37910.43134352983, 39298.41052384216, 39386.21227575454, 43218.351724724074, 39506.802465987705, 39890.202195956066, 40090.7995460023, 40104.0, 40111.19777604454, 7287.890681639779, 7656.196099409565, 7801.648925798324, 7801.843963120734, 7809.531428114303, 9648.324617276758, 9873.062059104373, 9921.0, 10309.690709278733, 10315.79368412635, 9701.660441884536, 9786.26603004774, 9794.0, 9798.608055677765, 9801.41191528507, 9939.55272012761, 9957.302988790787, 10010.799784004279, 10026.649067282682, 10030.699079027605, 9860.260480463994, 9921.454320012397, 9929.602815887356, 9934.205263578897, 9935.701928942144, 38411.615883841085, 38697.0, 38742.41886371707, 38772.41841372382, 38813.41779873304, 2955.3202763364548, 2994.2514371407146, 3037.711417415342, 3081.1064791210524, 3085.336652619675, 27807.860960695067, 29023.50768740692, 29440.263993400335, 29477.85261073681, 29701.405971880442, 3260.902172934792, 3261.59230096238, 3261.706446419834, 3277.3117645294515, 3296.6373698893103, 241009.79495102586, 241574.0, 241892.0, 241961.0, 242381.0, 51450.228246576335, 51783.74108129435, 51786.22320665193, 51810.22284665733, 51857.48142518602, 31947.0, 32575.348493030007, 32797.67202327994, 34659.82670086633, 34698.47952280718, 14308.569143085608, 14320.928395357956, 14429.639259018608, 14470.0, 14496.782548261785, 10251.692449226535, 10568.365898045988, 10712.892871071346, 11126.0, 11208.103351731841, 17590.38433654816, 17611.55971100733, 17632.0, 17640.0, 17712.46862594124, 6670.837181129369, 6706.329367063352, 6783.3555812197765, 6814.591124532544, 6967.477439192081, 782.765170448863, 813.5647428625657, 883.3904605821963, 889.8709687095335, 926.9304802139867, 25811.354716132246, 27821.582676259874, 27971.518892667198, 28097.578536321562, 28376.85811570929, 944.7307517357658, 989.1987490133015, 994.0, 1001.8647482589903, 1018.9214003432913, 13953.860461395256, 14577.355661084728, 14781.187034713193, 14798.63003424923, 15210.08739475635, 375853.0, 377261.0, 378257.0, 378631.0, 378651.0, 48382.5161748378, 48820.26769598459, 48874.0, 48915.75542122267, 48967.510324897004, 1055.18223376882, 1106.8948449897407, 1148.655403378983, 1170.0, 1176.5529098942347, 26464.0, 28605.570916435852, 28833.85583072155, 28964.0, 29336.413271734873, 41274.38088428736, 41601.99999999879, 41871.1625767489, 41941.161176776906, 41963.74108776742, 291453.0, 293616.0, 293704.0, 294090.0, 295051.0, 22424.88787556052, 22819.20132795344, 22901.31296061121, 22925.770742292363, 24836.751632483443, 41245.0, 41281.793591031856, 41288.174236515704, 41309.79345103255, 41436.72098034637, 35293.294134117685, 35401.645983539835, 35550.46674299836, 35556.0, 35729.28541429105, 35312.11719706925, 35422.0, 35577.28845423129, 35658.28683426369, 35672.821635891654, 34364.31271374608, 34408.655913441544, 34932.82533587417, 35152.0, 35624.287514250085, 29873.850630746707, 32399.676003239667, 32491.35017299688, 32523.674763252064, 32535.18662033468, 24606.0, 24791.132310369052, 24835.88293482285, 25131.74868251294, 25257.368565786004], "value": 17416.110911339627, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.89494256780415, 352.3077153393581, 365.7512891233961, 367.5644361431704, 375.92672918816777, 12609.747805043906, 15040.623984400396, 16478.34086636533, 16582.585435364123, 16616.750748738763, 15872.920635396807, 15881.126538040404, 16146.273417696202, 16285.51143465695, 16287.104209268486, 4701.318308845215, 4923.753812309388, 4958.677685950402, 4977.751112444382, 4980.526849949267, 3629.530040333669, 3808.8857334280015, 4933.432655244639, 4953.3560637117125, 5016.523430274118, 3819.9236015279675, 3838.9424158637644, 5664.575156863231, 5699.116636921284, 5802.65184088956, 5619.823157306109, 5774.133879918004, 5777.653340799566, 5813.6802475863815, 5884.47039766423, 5081.898362032775, 5095.439501654815, 5097.057044446789, 5104.106781313292, 5105.183170692709, 20566.38300850977, 21182.0, 21226.469338266514, 21456.35630931059, 21476.24833130833, 1794.605186858889, 1877.3898482992945, 1889.7070954002152, 1900.7433996410446, 1915.9329423470251, 12465.563705270362, 12822.166559173624, 12931.224126552437, 13196.472141114344, 13618.20826730518, 1707.2061491406555, 1721.4147189955474, 1725.767021452113, 1742.9825701742948, 1851.9444416667523, 66176.33823661749, 67499.3250067498, 68042.65978670125, 68064.6596767013, 69056.6547167261, 8836.602352894135, 9137.543122843832, 9288.535573221312, 9303.395279306958, 9652.179564736936, 12698.746025079447, 12721.173123746927, 12724.745505090032, 12744.872551274368, 12765.872341276654, 6590.209174899042, 7049.118860142482, 7168.605726685082, 7192.604406757679, 7256.818579535554, 6704.698288577026, 6803.965980170067, 6817.5227734059135, 6874.381305682413, 6938.5143039986715, 5784.88430231393, 5884.705764711747, 5892.793752218652, 5965.940340596625, 6175.783847565313, 1605.5664970458145, 1669.1153688545091, 1670.599056226497, 1703.4123227486498, 1742.0505824325774, 671.771597656799, 831.1895901495993, 853.8633818588996, 899.6221586933503, 917.7338571814099, 16893.746593801105, 17010.744838827428, 17720.645587088187, 17922.0, 18381.356652517097, 814.2752949874616, 844.7719115838812, 854.3122786157203, 854.525738215284, 882.4793371910604, 9735.269854760922, 10524.105451036748, 10525.684229473129, 10532.736681583021, 10572.52423640938, 362975.0, 371690.0, 373194.0, 374814.0, 376005.0, 70046.0, 71262.64368678123, 72533.0, 74466.62766686131, 77284.22715772883, 879.2877769006997, 895.5029958373166, 919.3334832246701, 919.384012711485, 923.1553128886993, 15381.615459613598, 15600.609984750472, 16032.438864639682, 16102.677946440772, 16279.918600406922, 35496.82251588725, 36013.81993090018, 36383.636163638024, 36621.61959472626, 38421.03947401226, 48623.0, 48851.51148488469, 49174.50825491699, 49631.503684962685, 50009.49990500194, 6712.228093769279, 6896.689648965909, 6942.548734332252, 7296.817579560554, 7619.619019049136, 10052.597896084368, 12170.695732606755, 12174.391280435766, 12387.62837114888, 12875.35623218854, 9945.900540994497, 10063.446510441703, 10119.747006324606, 10446.216533759847, 10983.340999539734, 10317.432541210306, 10566.0, 10667.839982400425, 10685.198610104118, 10966.561337546409, 10548.683539493828, 10763.569457221623, 10786.946065269623, 10832.891671083189, 11064.336139831636, 6857.417119544894, 7125.5368401053765, 7210.4231661468, 7259.564426134449, 7290.0, 8205.466644668077, 10641.733956651145, 10690.144788417063, 10870.891291086988, 11258.549658013588], "value": 8165.324482706787, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1306.3990564340406, 1367.2821768571498, 1390.3048475762118, 1620.4571468558033, 1641.171208539688, 7022.36798688118, 7300.1604815446235, 7643.388528917687, 7843.607819609018, 7854.939583156265, 7669.923300766991, 7977.282044615983, 7979.646004428693, 7980.082290536576, 7985.800354991128, 2134.6691262854283, 2165.0, 2166.6533354663256, 2166.674998750192, 2171.1641018208024, 1848.2422206895176, 2069.286096296775, 2109.156337465011, 2132.9040193191345, 2135.284679632325, 2167.696522486853, 2185.1914791527156, 2192.3861318830673, 2195.2536137713173, 2197.373748481683, 2127.914883404654, 2135.3807395855183, 2160.1791319298723, 2164.3074216250807, 2166.6641670540935, 2041.2141325589755, 2052.220156340596, 2067.214458505773, 2076.7404074490687, 2098.4858709616087, 7917.0, 8024.759257222293, 8031.598420078973, 8034.0, 8035.477693949874, 4285.692863676577, 4392.736435813861, 4395.824167033315, 4400.383946247528, 4403.955960440387, 2781.4019985702967, 2870.454613623405, 2917.9124626261246, 2935.765138788913, 2970.9405811883857, 893.6559424621568, 933.0809152984305, 944.23516951269, 945.2249155692335, 949.2500924269818, 10270.127039201678, 10281.074703276667, 10282.79434411315, 10285.331453455576, 10290.485475726258, 3676.852925882988, 4016.779077150785, 4120.114175452292, 4124.257633625976, 4141.6065473779945, 8892.644294228288, 8898.199162075443, 8898.955505222433, 8901.73294801157, 8903.19871211594, 4457.487388950248, 4459.10817836434, 4463.843765468193, 4466.0844526871915, 4473.46318441789, 4069.7761623110423, 4092.815823287959, 4094.2220978014084, 4095.5290141634096, 4095.5904409559403, 3355.9496607550905, 3367.4275373186615, 3376.324735052999, 3388.186835159593, 3447.3622379859808, 939.4081728510994, 1013.5742987945081, 1032.3289861590026, 1032.963846265377, 1044.9059584637419, 741.8924255982852, 768.2624680306968, 776.1191048160379, 785.2304741353391, 830.5515021888234, 5057.848264552069, 5083.567896728746, 5117.769700363493, 5139.8458046258675, 5187.584993200535, 763.5342441110918, 802.2859654907137, 851.9105493923049, 878.2578720980761, 882.7087061269766, 3514.9121271968406, 3528.9294214115575, 3537.4870643756512, 3546.078019714893, 3599.1644261426336, 9265.629374824932, 9683.612655493702, 9717.0, 9731.0, 9769.267304952156, 9537.523123843781, 9616.51917404127, 9624.326297159272, 9631.229501639851, 9645.42127472354, 7246.637668116573, 7324.315407456208, 7467.58928258951, 7478.0, 7482.738104166437, 4901.2893130496595, 5327.9200811987075, 5366.463353664759, 5473.151661492475, 5670.432956704297, 8902.154295341932, 8924.241439477457, 8955.686550970684, 8959.865602016105, 9210.447373157633, 8784.385093043426, 9180.770480738034, 9243.168114869693, 9351.111644393772, 9370.906290937004, 6561.1470508833545, 6650.900236496554, 6693.196816382066, 6693.765718199839, 6694.464442844658, 7205.639718013974, 7234.5659260444545, 7255.709771609288, 7281.344678978706, 7284.053073100463, 6436.259830119409, 6525.575837570543, 6527.640979745969, 6589.24223714279, 6726.428253598499, 6437.324080971431, 6508.316626754123, 6513.185851768529, 6533.379328963741, 6616.66916654175, 5393.6763794172475, 5668.943310566841, 5706.144078388275, 5708.229389032427, 5709.200711900255, 4223.0, 4331.068820203672, 4348.108637729195, 4362.803673834622, 4462.308342206963, 4311.698181127291, 4392.05570802279, 4463.732176069446, 4505.459344878635, 4553.3397657340165], "value": 4038.3624975802177, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.26082923106844, 297.67850721221083, 300.45467476530104, 305.2049411283605, 308.48483033334327, 4744.833930812422, 4780.545848144425, 4780.545848144425, 4784.808607655689, 4821.349117869091, 4715.78778954947, 4729.0, 4740.246108939688, 4749.833755818547, 4763.928541071887, 3597.550306211717, 3644.890653280406, 3754.6996240300846, 3791.791451470168, 3801.9619803802025, 3757.9436308455397, 3779.3197224499577, 3779.8677046303383, 3788.488554045196, 3803.1633040730994, 3716.256748650267, 3724.944125838115, 3737.6449237322413, 3739.7943113128767, 3762.473253744478, 3501.124718820295, 3590.8922732318074, 3705.166337574024, 3718.8698395556025, 3759.5676497202626, 3347.966520334814, 3354.8490317935753, 3360.294338188983, 3363.7477189210663, 3363.7813542119684, 4632.0967411354795, 4662.790174442158, 4672.345871577982, 4672.742999135046, 4683.414573178353, 4220.767857767822, 4294.141171765628, 4326.978365108186, 4327.415798867144, 4338.566143385668, 4904.509549045067, 4930.939847932712, 4933.7286449245275, 4957.231629097496, 4970.751462426864, 3661.9816900915325, 3662.8351724172476, 3669.321175582499, 3694.5935947045805, 3708.2583483303447, 5053.873653158664, 5123.487651234884, 5154.742262886878, 5211.472435868601, 5272.156454967187, 3700.703943684499, 3714.257148570297, 3725.441183822394, 3730.477733117366, 3751.643593858579, 3765.9623403765613, 3770.75639994203, 3790.905227369338, 3809.466674665549, 3821.197548514843, 4261.0, 4292.7424354538825, 4294.484661840599, 4297.269464191095, 4307.806148723315, 4889.608831293488, 4925.75371231437, 4926.3842019747535, 4934.53121953421, 5047.899042019212, 1285.8649841766667, 1294.3334182895817, 1296.6045356166271, 1302.5961951794784, 1304.8042793580848, 807.6527093349798, 835.1940377535586, 862.344618090256, 864.8140649760331, 867.280157469299, 4269.356297825359, 4413.955860441354, 4425.300566654339, 4445.088756804847, 4453.755043472575, 5045.369328833895, 5097.133487307166, 5108.5951363374625, 5112.923306150411, 5132.769025393866, 4416.734995900256, 4428.955710442919, 4444.200098959926, 4446.333050042519, 4464.749870036395, 2571.3443072016516, 2713.1046754570943, 2713.959290610603, 2714.9728502715116, 2725.2233113562934, 4797.960866631667, 4814.903701926012, 4824.6863953842885, 4825.855224343275, 4869.342638743795, 5004.523665518681, 5214.921776173284, 5223.373195216523, 5227.60792940531, 5235.931806936239, 4766.713997160112, 4778.402699662543, 4798.568128868418, 4809.591184749335, 4832.275158726219, 5109.335786347751, 5116.360454943132, 5121.283020377075, 5132.2814805926455, 5132.820351287687, 4816.373871396696, 5051.595872330131, 5053.0, 5063.291139240436, 5066.0, 4839.346688197048, 4854.975725121351, 4870.537298956593, 4875.9512404875495, 4885.1206782779445, 3642.5446819147605, 3653.8173091345857, 3676.632336766302, 3692.5753538343433, 3694.408894576855, 5026.874328141826, 5205.401378841481, 5212.791488340576, 5231.686098834082, 5247.527722504993, 4959.652824302264, 4966.279889416087, 4978.502149784992, 5006.5994720423005, 5011.373578302712, 4945.873274492003, 4953.554180123806, 4978.60171186297, 4983.626228032839, 5001.824936127218, 4660.254359302496, 4912.361393019028, 4926.778294976663, 4954.182559877724, 4961.70229786214, 4727.125481785881, 4889.951100488949, 4973.900521989613, 4980.128477516492, 4980.576650984562, 4858.319835223142, 4859.222524396079, 4866.416030076271, 4872.658913875995, 4888.242322440027], "value": 3778.285828493632, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.692703257822, 598.5959477352786, 611.9326874043852, 656.4092316914779, 686.9793906182813, 11535.769284614302, 12434.316112613826, 12437.68905777356, 12457.563985260518, 12581.24512529249, 12649.747005059893, 12746.68133296668, 12759.425825837836, 12772.489100435994, 12825.03812214083, 604.0, 704.5737328916009, 727.2799928071224, 730.8319086610082, 742.5025233093841, 725.5791640848322, 726.0053726394847, 726.7165805335924, 727.8980942668031, 729.5513259345491, 754.1063839350387, 755.0863455219192, 755.0976582983337, 755.1391413788285, 755.7052749427728, 625.6746491824272, 751.9022527071501, 756.1719916691214, 775.1783109903521, 779.3998621061751, 565.9377468478506, 566.6458463460337, 566.6458463460337, 566.9121286200645, 567.0898208375539, 11710.063194944383, 11715.062794976382, 11720.589779357766, 11734.47194876224, 11738.0, 10925.562977480971, 11319.434028298634, 11653.30080195191, 11703.2978021319, 12301.446434910362, 10686.786264274671, 10718.517666705016, 10726.570937162503, 10796.406197659126, 10873.67378978624, 3856.6529012388737, 3881.126746482047, 3894.941575876364, 3901.7854018028997, 4190.958090419118, 63127.0, 63669.681651591905, 64924.350756492306, 65814.67092664554, 66548.3345166547, 9063.773405664779, 9194.678186263449, 9373.156415922464, 9397.060293970548, 9446.38598491096, 16810.91594542044, 17023.148842558072, 17037.0, 17042.31830726782, 17057.147142642818, 5928.525717942554, 6074.726637301332, 6095.238095238095, 6209.416598767376, 6232.345603711545, 3482.3209474152804, 3575.284943011408, 3579.874704385334, 3615.313090512794, 3707.6848467879995, 2716.375233696261, 2813.6482939632547, 2814.5356016257497, 2817.29567608098, 2825.5620378841313, 474.44016061048296, 476.9332293478916, 480.97595120244546, 484.1285685765608, 490.22054932657244, 445.45431845988406, 448.43497193536376, 450.3020318506302, 495.36098433021, 566.5382713088819, 7656.425768067417, 7795.688172473151, 7849.332806711379, 8113.716019939392, 8337.124601916832, 464.53778490402266, 468.72111093899076, 520.2872065270603, 546.5490969949807, 566.6118708684564, 3488.075659950115, 3509.964900351015, 3699.5375578052744, 3827.770333779926, 3985.1631157456677, 52489.302995161415, 84491.57754211313, 87574.0, 87870.56064719635, 89057.55471222602, 64883.0, 66092.0, 66645.0, 77178.0, 78687.60656196797, 64695.0, 66110.33889661042, 66162.33837661658, 67367.32632673807, 67496.66251668711, 7694.192109828387, 7940.28537431634, 7943.523388596703, 8052.758417247492, 8163.510189388418, 42221.0, 42986.0, 43021.35467968046, 43345.78327108344, 44229.55770442254, 53639.463605363446, 55351.0, 60777.0, 61584.38415615966, 63075.0, 10342.172626190037, 10557.313774604627, 10725.89274107249, 10791.568337266735, 11351.205415621158, 11823.113266504875, 12052.216605920587, 12414.255144690986, 12779.61661150167, 13167.934160329138, 10329.638462653771, 10480.213983951387, 10614.469276535989, 11185.496652650487, 11199.72000699989, 10102.848457273, 11165.776684466102, 11225.270357426742, 11588.594399195988, 11844.28934263947, 10985.505652245827, 11163.553457861595, 11527.365994870363, 11905.285682859056, 11907.226030308002, 8321.916780832114, 8519.762490253912, 8669.91330086691, 8838.762911194808, 8936.553172341486, 8902.287816974496, 9311.301652375963, 9471.0, 9536.133784590827, 9685.0], "value": 6138.045127957332, "unit": "score"}], "name": "dromaeo_css", "value": 6288.158310086441, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 114.0, 116.0, 115.0, 117.0, 107.0, 115.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 130.0, 151.0, 193.0, 134.0, 157.0, 155.0, 137.0, 185.0, 123.0], "value": 151.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 163.0, 164.0, 163.0, 164.0, 165.0, 164.0, 164.0, 163.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 176.0, 76.0, 77.0, 76.0, 77.0, 172.0, 75.0], "value": 95.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 141.0, 84.0, 83.0, 83.0, 146.0, 83.0, 82.0, 144.0], "value": 101.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 119.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 146.0, 147.0, 146.0, 147.0, 148.0, 147.0, 147.0, 147.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 64.0, 65.0, 66.0, 64.0, 64.0, 64.0, 64.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 67.0, 66.0, 67.0, 69.0, 65.0, 66.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 83.0, 91.0, 86.0, 82.0, 85.0, 83.0, 83.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 133.0, 133.0, 136.0, 138.0, 135.0, 135.0, 144.0, 179.0, 133.0], "value": 140.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 178.0, 179.0, 174.0, 184.0, 176.0, 179.0, 176.0, 179.0, 175.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 70.0, 69.0, 70.0, 69.0, 69.0, 69.0, 68.0, 72.0], "value": 69.9, "unit": "score"}], "name": "kraken", "value": 1589.8, "alertThreshold": 2.0}]} 02:54:29 INFO - Return code: 0 02:54:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:54:29 INFO - # TBPL SUCCESS # 02:54:29 INFO - Running post-action listener: _resource_record_post_action 02:54:29 INFO - [mozharness: 2016-08-30 09:54:29.845049Z] Finished run-tests step (success) 02:54:29 INFO - Running post-run listener: _resource_record_post_run 02:54:29 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 8192; Write bytes: 416940032; Read time: 132; Write time: 455740 02:54:29 INFO - TinderboxPrint: CPU usage
13.0% 02:54:29 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 132 02:54:29 INFO - TinderboxPrint: I/O write bytes / time
416,940,032 / 455,740 02:54:29 INFO - TinderboxPrint: CPU idle
8,354.6 (87.0%) 02:54:29 INFO - TinderboxPrint: CPU user
1,202.3 (12.5%) 02:54:29 INFO - TinderboxPrint: Swap in / out
0 / 0 02:54:29 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 124977152; Read time: 0; Write time: 79848 02:54:29 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291962880; Read time: 124; Write time: 375892 02:54:30 INFO - Running post-run listener: _upload_blobber_files 02:54:30 INFO - Blob upload gear active. 02:54:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:54:30 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:54:30 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'] 02:54:30 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 02:54:30 INFO - (blobuploader) - INFO - Open directory for files ... 02:54:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:54:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:54:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:54:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:54:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:54:34 INFO - (blobuploader) - INFO - Done attempting. 02:54:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:54:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:54:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:54:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:54:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:54:35 INFO - (blobuploader) - INFO - Done attempting. 02:54:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:54:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:54:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:54:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:54:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:54:36 INFO - (blobuploader) - INFO - Done attempting. 02:54:36 INFO - (blobuploader) - INFO - Iteration through files over. 02:54:36 INFO - Return code: 0 02:54:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:54:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:54:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/914586a8df8b57edf4f051f49c5dbd930bb7f1666cf87e3e8347a722419ae55330ab30011346f11374024f57e616800811fb1d994024db6737b12fc5c94119b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/757d710b00dc5512f9657a6f9614e5cdea566e2d33bf58807fc4014bbe819dae50c1d08740b54c61f950cc2b229e2df40aae909f11a88435ffe967ff24d69e63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/895709539207e1209b0c8fca3793266121865e7fb13c8bb70950dd5b2fad9872d7bdd5faf9023be62cb173511be26028a7b06d04814d031128b7beb233142c7a"} 02:54:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:54:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:54:36 INFO - Contents: 02:54:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/914586a8df8b57edf4f051f49c5dbd930bb7f1666cf87e3e8347a722419ae55330ab30011346f11374024f57e616800811fb1d994024db6737b12fc5c94119b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/757d710b00dc5512f9657a6f9614e5cdea566e2d33bf58807fc4014bbe819dae50c1d08740b54c61f950cc2b229e2df40aae909f11a88435ffe967ff24d69e63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/895709539207e1209b0c8fca3793266121865e7fb13c8bb70950dd5b2fad9872d7bdd5faf9023be62cb173511be26028a7b06d04814d031128b7beb233142c7a"} 02:54:36 INFO - Running post-run listener: copy_logs_to_upload_dir 02:54:36 INFO - Copying logs to upload dir... 02:54:36 INFO - mkdir: /builds/slave/test/build/upload/logs 02:54:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.610020 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-30 02:54:35.667991) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 02:54:35.674596) ========= 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=5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468 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/914586a8df8b57edf4f051f49c5dbd930bb7f1666cf87e3e8347a722419ae55330ab30011346f11374024f57e616800811fb1d994024db6737b12fc5c94119b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/757d710b00dc5512f9657a6f9614e5cdea566e2d33bf58807fc4014bbe819dae50c1d08740b54c61f950cc2b229e2df40aae909f11a88435ffe967ff24d69e63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/895709539207e1209b0c8fca3793266121865e7fb13c8bb70950dd5b2fad9872d7bdd5faf9023be62cb173511be26028a7b06d04814d031128b7beb233142c7a"} build_url:https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011324 build_url: 'https://queue.taskcluster.net/v1/task/EOqEJ_imR8C_8oxNHt9dxQ/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/914586a8df8b57edf4f051f49c5dbd930bb7f1666cf87e3e8347a722419ae55330ab30011346f11374024f57e616800811fb1d994024db6737b12fc5c94119b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/757d710b00dc5512f9657a6f9614e5cdea566e2d33bf58807fc4014bbe819dae50c1d08740b54c61f950cc2b229e2df40aae909f11a88435ffe967ff24d69e63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/895709539207e1209b0c8fca3793266121865e7fb13c8bb70950dd5b2fad9872d7bdd5faf9023be62cb173511be26028a7b06d04814d031128b7beb233142c7a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 02:54:35.704240) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 02:54:35.704554) ========= 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=5bd038846534b2fdfa68eeae000001e2-1472548452.92082-346718468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 02:54:35.783756) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 02:54:35.787103) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 02:54:35.787409) ========= ========= Total master_lag: 0.19 =========