builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-027 starttime: 1471415248.24 results: success (0) buildid: 20160816203016 builduid: 880fa5f776a744ba9c03deef681e14fc revision: d83220df469dca35a747895c64a7d8120e79e556 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 23:27:28.245297) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 23:27:28.245891) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 23:27:28.246216) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 23:27:28.299274) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 23:27:28.299611) ========= 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=1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-16 23:27:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.2M=0s 2016-08-16 23:27:28 (42.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101554 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 23:27:28.415533) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 23:27:28.415906) ========= 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=1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017259 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 23:27:28.466912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 23:27:28.467230) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d83220df469dca35a747895c64a7d8120e79e556 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d83220df469dca35a747895c64a7d8120e79e556 --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=1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-16 23:27:28,811 truncating revision to first 12 chars 2016-08-16 23:27:28,811 Setting DEBUG logging. 2016-08-16 23:27:28,811 attempt 1/10 2016-08-16 23:27:28,811 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d83220df469d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 23:27:29,184 unpacking tar archive at: fx-team-d83220df469d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.735970 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 23:27:29.216831) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 23:27:29.217148) ========= script_repo_revision: d83220df469dca35a747895c64a7d8120e79e556 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 23:27:29.217564) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 23:27:29.217829) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 23:27:29.227467) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-16 23:27:29.227834) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:27:29 INFO - MultiFileLogger online at 20160816 23:27:29 in /builds/slave/test 23:27:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:27:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:27:29 INFO - {'append_to_log': False, 23:27:29 INFO - 'base_work_dir': '/builds/slave/test', 23:27:29 INFO - 'blob_upload_branch': 'Fx-Team', 23:27:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:27:29 INFO - 'branch': 'Fx-Team', 23:27:29 INFO - 'buildbot_json_path': 'buildprops.json', 23:27:29 INFO - 'config_files': ('talos/linux_config.py',), 23:27:29 INFO - 'default_actions': ('clobber', 23:27:29 INFO - 'read-buildbot-config', 23:27:29 INFO - 'download-and-extract', 23:27:29 INFO - 'populate-webroot', 23:27:29 INFO - 'create-virtualenv', 23:27:29 INFO - 'install', 23:27:29 INFO - 'run-tests'), 23:27:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:27:29 INFO - 'download_minidump_stackwalk': True, 23:27:29 INFO - 'download_symbols': 'ondemand', 23:27:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:27:29 INFO - 'tooltool.py': '/tools/tooltool.py', 23:27:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:27:29 INFO - '/tools/misc-python/virtualenv.py')}, 23:27:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:27:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:27:29 INFO - 'installer_path': 'installer.exe', 23:27:29 INFO - 'log_level': 'info', 23:27:29 INFO - 'log_name': 'talos', 23:27:29 INFO - 'log_to_console': True, 23:27:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:27:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:27:29 INFO - 'opt_config_files': (), 23:27:29 INFO - 'pip_index': False, 23:27:29 INFO - 'sps_profile': False, 23:27:29 INFO - 'sps_profile_interval': 0, 23:27:29 INFO - 'suite': 'dromaeojs', 23:27:29 INFO - 'system_bits': '32', 23:27:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:27:29 INFO - 'title': 'talos-linux64-ix-027', 23:27:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:27:29 INFO - 'use_talos_json': True, 23:27:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:27:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:27:29 INFO - 'work_dir': 'build'} 23:27:29 INFO - [mozharness: 2016-08-17 06:27:29.674100Z] Running clobber step. 23:27:29 INFO - Running pre-action listener: _resource_record_pre_action 23:27:29 INFO - Running main action method: clobber 23:27:29 INFO - rmtree: /builds/slave/test/build 23:27:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:27:30 INFO - Running post-action listener: _resource_record_post_action 23:27:30 INFO - [mozharness: 2016-08-17 06:27:30.648319Z] Finished clobber step (success) 23:27:30 INFO - [mozharness: 2016-08-17 06:27:30.648420Z] Running read-buildbot-config step. 23:27:30 INFO - Running pre-action listener: _resource_record_pre_action 23:27:30 INFO - Running main action method: read_buildbot_config 23:27:30 INFO - Using buildbot properties: 23:27:30 INFO - { 23:27:30 INFO - "project": "", 23:27:30 INFO - "product": "firefox", 23:27:30 INFO - "script_repo_revision": "production", 23:27:30 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:27:30 INFO - "repository": "", 23:27:30 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:27:30 INFO - "buildid": "20160816203016", 23:27:30 INFO - "pgo_build": "True", 23:27:30 INFO - "basedir": "/builds/slave/test", 23:27:30 INFO - "buildnumber": 1469, 23:27:30 INFO - "slavename": "talos-linux64-ix-027", 23:27:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:27:30 INFO - "platform": "ubuntu64_hw", 23:27:30 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:27:30 INFO - "branch": "fx-team", 23:27:30 INFO - "repo_path": "integration/fx-team", 23:27:30 INFO - "revision": "d83220df469dca35a747895c64a7d8120e79e556", 23:27:30 INFO - "stage_platform": "linux64-pgo", 23:27:30 INFO - "builduid": "880fa5f776a744ba9c03deef681e14fc", 23:27:30 INFO - "slavebuilddir": "test" 23:27:30 INFO - } 23:27:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 23:27:30 INFO - Running post-action listener: _resource_record_post_action 23:27:30 INFO - [mozharness: 2016-08-17 06:27:30.650869Z] Finished read-buildbot-config step (success) 23:27:30 INFO - [mozharness: 2016-08-17 06:27:30.650957Z] Running download-and-extract step. 23:27:30 INFO - Running pre-action listener: _resource_record_pre_action 23:27:30 INFO - Running main action method: download_and_extract 23:27:30 INFO - mkdir: /builds/slave/test/build/tests 23:27:30 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')]} 23:27:30 INFO - trying https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 23:27:30 INFO - Downloading https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/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 23:27:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/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 23:27:31 INFO - Downloaded 1361 bytes. 23:27:31 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 23:27:31 INFO - Using the following test package requirements: 23:27:31 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 23:27:31 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:27:31 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:27:31 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:31 INFO - u'jsshell-linux-x86_64.zip'], 23:27:31 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:27:31 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 23:27:31 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:27:31 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:27:31 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:27:31 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:31 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:27:31 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:27:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:31 INFO - trying https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:27:31 INFO - Downloading https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/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 23:27:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/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 23:27:32 INFO - Downloaded 21837609 bytes. 23:27:32 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 23:27:36 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 23:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:36 INFO - trying https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:27:36 INFO - Downloading https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/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 23:27:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/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 23:27:37 INFO - Downloaded 21837609 bytes. 23:27:37 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 23:27: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')]} 23:27:41 INFO - trying https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 23:27:41 INFO - Downloading https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/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 23:27:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/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 23:27:42 INFO - Downloaded 14413981 bytes. 23:27:42 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 23:27:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:42 INFO - trying https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:27:42 INFO - Downloading https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/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 23:27:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/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 23:27:44 INFO - Downloaded 59374280 bytes. 23:27:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:27:44 INFO - mkdir: /builds/slave/test/properties 23:27:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:27:44 INFO - Writing to file /builds/slave/test/properties/build_url 23:27:44 INFO - Contents: 23:27:44 INFO - build_url:https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:27:44 INFO - Running post-action listener: _resource_record_post_action 23:27:44 INFO - Running post-action listener: set_extra_try_arguments 23:27:44 INFO - [mozharness: 2016-08-17 06:27:44.676795Z] Finished download-and-extract step (success) 23:27:44 INFO - [mozharness: 2016-08-17 06:27:44.677020Z] Running populate-webroot step. 23:27:44 INFO - Running pre-action listener: _resource_record_pre_action 23:27:44 INFO - Running main action method: populate_webroot 23:27:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:27:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:27:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:27:44 INFO - '--tppagecycles', 23:27:44 INFO - '7'], 23:27:44 INFO - 'tests': ['tsvgm']}}, 23:27:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:27:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:27:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:27:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:27:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:27:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:27:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:44 INFO - 'talos_options': ['--disable-e10s'], 23:27:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:27:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:27:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:44 INFO - 'talos_options': ['--disable-e10s'], 23:27:44 INFO - 'tests': ['damp', 'tps']}, 23:27:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:44 INFO - 'tests': ['damp', 'tps']}, 23:27:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:27:44 INFO - 'tests': ['dromaeo_dom']}, 23:27:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:27:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:27:44 INFO - 'tests': ['basic_compositor_video']}, 23:27:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:27:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:27:44 INFO - 'tests': ['a11yr', 23:27:44 INFO - 'ts_paint', 23:27:44 INFO - 'tpaint', 23:27:44 INFO - 'sessionrestore', 23:27:44 INFO - 'sessionrestore_no_auto_restore', 23:27:44 INFO - 'tabpaint']}, 23:27:44 INFO - 'other-e10s': {'tests': ['a11yr', 23:27:44 INFO - 'ts_paint', 23:27:44 INFO - 'tpaint', 23:27:44 INFO - 'sessionrestore', 23:27:44 INFO - 'sessionrestore_no_auto_restore', 23:27:44 INFO - 'tabpaint']}, 23:27:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:27:44 INFO - 'ts_paint', 23:27:44 INFO - 'tpaint', 23:27:44 INFO - 'sessionrestore', 23:27:44 INFO - 'sessionrestore_no_auto_restore', 23:27:44 INFO - 'tabpaint']}, 23:27:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:27:44 INFO - 'ts_paint', 23:27:44 INFO - 'tpaint', 23:27:44 INFO - 'sessionrestore', 23:27:44 INFO - 'sessionrestore_no_auto_restore', 23:27:44 INFO - 'tabpaint']}, 23:27:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:27:44 INFO - 'tests': ['a11yr', 23:27:44 INFO - 'ts_paint', 23:27:44 INFO - 'tpaint', 23:27:44 INFO - 'sessionrestore', 23:27:44 INFO - 'sessionrestore_no_auto_restore', 23:27:44 INFO - 'tabpaint']}, 23:27:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:27:44 INFO - 'tests': ['a11yr', 23:27:44 INFO - 'ts_paint', 23:27:44 INFO - 'tpaint', 23:27:44 INFO - 'sessionrestore', 23:27:44 INFO - 'sessionrestore_no_auto_restore', 23:27:44 INFO - 'tabpaint']}, 23:27:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:27:44 INFO - 'tests': ['tsvgx', 23:27:44 INFO - 'tsvgr_opacity', 23:27:44 INFO - 'tart', 23:27:44 INFO - 'tscrollx', 23:27:44 INFO - 'cart']}, 23:27:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:27:44 INFO - 'tsvgr_opacity', 23:27:44 INFO - 'tart', 23:27:44 INFO - 'tscrollx', 23:27:44 INFO - 'cart']}, 23:27:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:44 INFO - 'talos_options': ['--disable-e10s'], 23:27:44 INFO - 'tests': ['tp5o']}, 23:27:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:44 INFO - 'tests': ['tp5o']}, 23:27:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:44 INFO - 'talos_options': ['--disable-e10s', 23:27:44 INFO - '--xperf_path', 23:27:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:27:44 INFO - 'tests': ['tp5n']}, 23:27:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:44 INFO - 'talos_options': ['--xperf_path', 23:27:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:27:44 INFO - 'tests': ['tp5n']}}, 23:27:44 INFO - 'talos.zip': {'path': '', 23:27:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:27:44 INFO - Running post-action listener: _resource_record_post_action 23:27:44 INFO - [mozharness: 2016-08-17 06:27:44.695714Z] Finished populate-webroot step (success) 23:27:44 INFO - [mozharness: 2016-08-17 06:27:44.695803Z] Running create-virtualenv step. 23:27:44 INFO - Running pre-action listener: _resource_record_pre_action 23:27:44 INFO - Running main action method: create_virtualenv 23:27:44 INFO - Creating virtualenv /builds/slave/test/build/venv 23:27:44 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 23:27:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:27:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:27:44 INFO - Using real prefix '/usr' 23:27:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:27:45 INFO - Installing distribute.............................................................................................................................................................................................done. 23:27:47 INFO - Installing pip.................done. 23:27:47 INFO - Return code: 0 23:27:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:27:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:47 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 0x7fe6d5390f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aa0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9b370>, '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 0x2a9e8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9ed80>, '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': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', '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 23:27:47 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 23:27:47 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 23:27:47 INFO - Using env: {'DISPLAY': ':0', 23:27:47 INFO - 'HOME': '/home/cltbld', 23:27:47 INFO - 'LANG': 'en_US.UTF-8', 23:27:47 INFO - 'LANGUAGE': 'en_US:en', 23:27:47 INFO - 'LOGNAME': 'cltbld', 23:27:47 INFO - 'MAIL': '/var/mail/cltbld', 23:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:47 INFO - 'MOZ_NO_REMOTE': '1', 23:27:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:27:47 INFO - 'NO_EM_RESTART': '1', 23:27:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:47 INFO - 'PWD': '/builds/slave/test', 23:27:47 INFO - 'SHELL': '/bin/bash', 23:27:47 INFO - 'SHLVL': '1', 23:27:47 INFO - 'TERM': 'linux', 23:27:47 INFO - 'TMOUT': '86400', 23:27:47 INFO - 'USER': 'cltbld', 23:27:47 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', 23:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:47 INFO - '_': '/tools/buildbot/bin/python'} 23:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:47 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 23:27:47 INFO - Cleaning up... 23:27:47 INFO - Return code: 0 23:27:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:27:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:47 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 0x7fe6d5390f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aa0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9b370>, '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 0x2a9e8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9ed80>, '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': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', '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 23:27:47 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 23:27:47 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 23:27:47 INFO - Using env: {'DISPLAY': ':0', 23:27:47 INFO - 'HOME': '/home/cltbld', 23:27:47 INFO - 'LANG': 'en_US.UTF-8', 23:27:47 INFO - 'LANGUAGE': 'en_US:en', 23:27:47 INFO - 'LOGNAME': 'cltbld', 23:27:47 INFO - 'MAIL': '/var/mail/cltbld', 23:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:47 INFO - 'MOZ_NO_REMOTE': '1', 23:27:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:27:47 INFO - 'NO_EM_RESTART': '1', 23:27:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:47 INFO - 'PWD': '/builds/slave/test', 23:27:47 INFO - 'SHELL': '/bin/bash', 23:27:47 INFO - 'SHLVL': '1', 23:27:47 INFO - 'TERM': 'linux', 23:27:47 INFO - 'TMOUT': '86400', 23:27:47 INFO - 'USER': 'cltbld', 23:27:47 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', 23:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:47 INFO - '_': '/tools/buildbot/bin/python'} 23:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:47 INFO - Downloading/unpacking psutil>=3.1.1 23:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:27:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:27:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:27:49 INFO - Installing collected packages: psutil 23:27:49 INFO - Running setup.py install for psutil 23:27:49 INFO - building 'psutil._psutil_linux' extension 23:27:49 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 23:27:49 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 23:27:49 INFO - building 'psutil._psutil_posix' extension 23:27:49 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 23:27:50 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 23:27:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:27:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:27:50 INFO - Successfully installed psutil 23:27:50 INFO - Cleaning up... 23:27:50 INFO - Return code: 0 23:27:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6d5390f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aa0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9b370>, '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 0x2a9e8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9ed80>, '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': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', '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 23:27:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:27:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:27:50 INFO - Using env: {'DISPLAY': ':0', 23:27:50 INFO - 'HOME': '/home/cltbld', 23:27:50 INFO - 'LANG': 'en_US.UTF-8', 23:27:50 INFO - 'LANGUAGE': 'en_US:en', 23:27:50 INFO - 'LOGNAME': 'cltbld', 23:27:50 INFO - 'MAIL': '/var/mail/cltbld', 23:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:50 INFO - 'MOZ_NO_REMOTE': '1', 23:27:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:27:50 INFO - 'NO_EM_RESTART': '1', 23:27:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:50 INFO - 'PWD': '/builds/slave/test', 23:27:50 INFO - 'SHELL': '/bin/bash', 23:27:50 INFO - 'SHLVL': '1', 23:27:50 INFO - 'TERM': 'linux', 23:27:50 INFO - 'TMOUT': '86400', 23:27:50 INFO - 'USER': 'cltbld', 23:27:50 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', 23:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:50 INFO - '_': '/tools/buildbot/bin/python'} 23:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:27:50 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 23:27:50 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 23:27:50 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 23:27:50 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 23:27:50 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 23:27:50 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 23:27:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:27:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:27:52 INFO - Installing collected packages: mozsystemmonitor 23:27:52 INFO - Running setup.py install for mozsystemmonitor 23:27:52 INFO - Successfully installed mozsystemmonitor 23:27:52 INFO - Cleaning up... 23:27:52 INFO - Return code: 0 23:27:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fe6d5390f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aa0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9b370>, '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 0x2a9e8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9ed80>, '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': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', '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 23:27:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:27:52 INFO - Using env: {'DISPLAY': ':0', 23:27:52 INFO - 'HOME': '/home/cltbld', 23:27:52 INFO - 'LANG': 'en_US.UTF-8', 23:27:52 INFO - 'LANGUAGE': 'en_US:en', 23:27:52 INFO - 'LOGNAME': 'cltbld', 23:27:52 INFO - 'MAIL': '/var/mail/cltbld', 23:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:52 INFO - 'MOZ_NO_REMOTE': '1', 23:27:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:27:52 INFO - 'NO_EM_RESTART': '1', 23:27:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:52 INFO - 'PWD': '/builds/slave/test', 23:27:52 INFO - 'SHELL': '/bin/bash', 23:27:52 INFO - 'SHLVL': '1', 23:27:52 INFO - 'TERM': 'linux', 23:27:52 INFO - 'TMOUT': '86400', 23:27:52 INFO - 'USER': 'cltbld', 23:27:52 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', 23:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:52 INFO - '_': '/tools/buildbot/bin/python'} 23:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:52 INFO - Downloading/unpacking jsonschema==2.5.1 23:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:27:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:54 INFO - Downloading functools32-3.2.3-2.tar.gz 23:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:27:54 INFO - warning: no files found matching '*.txt' 23:27:54 INFO - no previously-included directories found matching 'build' 23:27:54 INFO - no previously-included directories found matching 'dist' 23:27:54 INFO - no previously-included directories found matching '.git*' 23:27:54 INFO - Installing collected packages: jsonschema, functools32 23:27:55 INFO - Running setup.py install for functools32 23:27:55 INFO - warning: no files found matching '*.txt' 23:27:55 INFO - no previously-included directories found matching 'build' 23:27:55 INFO - no previously-included directories found matching 'dist' 23:27:55 INFO - no previously-included directories found matching '.git*' 23:27:55 INFO - Successfully installed jsonschema functools32 23:27:55 INFO - Cleaning up... 23:27:55 INFO - Return code: 0 23:27:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:27: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')]} 23:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27: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')]} 23:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:55 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 0x7fe6d5390f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aa0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9b370>, '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 0x2a9e8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9ed80>, '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': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', '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 23:27:55 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 23:27:55 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 23:27:55 INFO - Using env: {'DISPLAY': ':0', 23:27:55 INFO - 'HOME': '/home/cltbld', 23:27:55 INFO - 'LANG': 'en_US.UTF-8', 23:27:55 INFO - 'LANGUAGE': 'en_US:en', 23:27:55 INFO - 'LOGNAME': 'cltbld', 23:27:55 INFO - 'MAIL': '/var/mail/cltbld', 23:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:55 INFO - 'MOZ_NO_REMOTE': '1', 23:27:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:27:55 INFO - 'NO_EM_RESTART': '1', 23:27:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:55 INFO - 'PWD': '/builds/slave/test', 23:27:55 INFO - 'SHELL': '/bin/bash', 23:27:55 INFO - 'SHLVL': '1', 23:27:55 INFO - 'TERM': 'linux', 23:27:55 INFO - 'TMOUT': '86400', 23:27:55 INFO - 'USER': 'cltbld', 23:27:55 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', 23:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:55 INFO - '_': '/tools/buildbot/bin/python'} 23:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:27:55 INFO - Cleaning up... 23:27:55 INFO - Return code: 0 23:27:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:27: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')]} 23:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27: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')]} 23:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:55 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 0x7fe6d5390f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aa0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9b370>, '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 0x2a9e8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9ed80>, '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': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', '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 23:27:55 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 23:27:55 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 23:27:55 INFO - Using env: {'DISPLAY': ':0', 23:27:55 INFO - 'HOME': '/home/cltbld', 23:27:55 INFO - 'LANG': 'en_US.UTF-8', 23:27:55 INFO - 'LANGUAGE': 'en_US:en', 23:27:55 INFO - 'LOGNAME': 'cltbld', 23:27:55 INFO - 'MAIL': '/var/mail/cltbld', 23:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:55 INFO - 'MOZ_NO_REMOTE': '1', 23:27:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:27:55 INFO - 'NO_EM_RESTART': '1', 23:27:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:55 INFO - 'PWD': '/builds/slave/test', 23:27:55 INFO - 'SHELL': '/bin/bash', 23:27:55 INFO - 'SHLVL': '1', 23:27:55 INFO - 'TERM': 'linux', 23:27:55 INFO - 'TMOUT': '86400', 23:27:55 INFO - 'USER': 'cltbld', 23:27:55 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', 23:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:55 INFO - '_': '/tools/buildbot/bin/python'} 23:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:55 INFO - Downloading/unpacking blobuploader==1.2.4 23:27:55 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 23:27:55 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 23:27:55 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 23:27:55 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 23:27:55 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 23:27:55 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 23:27:57 INFO - Downloading blobuploader-1.2.4.tar.gz 23:27:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:27:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:27:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:58 INFO - Downloading docopt-0.6.1.tar.gz 23:27:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:27:58 INFO - Installing collected packages: blobuploader, requests, docopt 23:27:58 INFO - Running setup.py install for blobuploader 23:27:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:27:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:27:58 INFO - Running setup.py install for requests 23:27:58 INFO - Running setup.py install for docopt 23:27:58 INFO - Successfully installed blobuploader requests docopt 23:27:58 INFO - Cleaning up... 23:27:58 INFO - Return code: 0 23:27:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:58 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 0x7fe6d5390f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aa0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9b370>, '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 0x2a9e8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9ed80>, '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': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', '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 23:27:58 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 23:27:58 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 23:27:58 INFO - Using env: {'DISPLAY': ':0', 23:27:58 INFO - 'HOME': '/home/cltbld', 23:27:58 INFO - 'LANG': 'en_US.UTF-8', 23:27:58 INFO - 'LANGUAGE': 'en_US:en', 23:27:58 INFO - 'LOGNAME': 'cltbld', 23:27:58 INFO - 'MAIL': '/var/mail/cltbld', 23:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:58 INFO - 'MOZ_NO_REMOTE': '1', 23:27:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:27:58 INFO - 'NO_EM_RESTART': '1', 23:27:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:58 INFO - 'PWD': '/builds/slave/test', 23:27:58 INFO - 'SHELL': '/bin/bash', 23:27:58 INFO - 'SHLVL': '1', 23:27:58 INFO - 'TERM': 'linux', 23:27:58 INFO - 'TMOUT': '86400', 23:27:58 INFO - 'USER': 'cltbld', 23:27:58 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', 23:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:58 INFO - '_': '/tools/buildbot/bin/python'} 23:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:27:59 INFO - Running setup.py (path:/tmp/pip-8Oskvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:27:59 INFO - Running setup.py (path:/tmp/pip-sNBLjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:27:59 INFO - Running setup.py (path:/tmp/pip-IpM2ef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:27:59 INFO - Running setup.py (path:/tmp/pip-pQVBtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:27:59 INFO - Running setup.py (path:/tmp/pip-FAADv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:27:59 INFO - Running setup.py (path:/tmp/pip-Q8CL8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:27:59 INFO - Running setup.py (path:/tmp/pip-vK_RAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:27:59 INFO - Running setup.py (path:/tmp/pip-ZsKPaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:27:59 INFO - Running setup.py (path:/tmp/pip-q_cm4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:27:59 INFO - Running setup.py (path:/tmp/pip-Mz3xwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:27:59 INFO - Running setup.py (path:/tmp/pip-U6FVJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:27:59 INFO - Running setup.py (path:/tmp/pip-TFj1Nz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:27:59 INFO - Running setup.py (path:/tmp/pip-Vj8qVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:28:00 INFO - Running setup.py (path:/tmp/pip-UZK3o_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:00 INFO - Running setup.py (path:/tmp/pip-geArWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:28:00 INFO - Running setup.py (path:/tmp/pip-6YpN0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:28:00 INFO - Running setup.py (path:/tmp/pip-l2e8h4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:28:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:28:00 INFO - Running setup.py install for manifestparser 23:28:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:28:00 INFO - Running setup.py install for mozcrash 23:28:00 INFO - Running setup.py install for mozdebug 23:28:00 INFO - Running setup.py install for mozdevice 23:28:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:28:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:28:00 INFO - Running setup.py install for mozfile 23:28:00 INFO - Running setup.py install for mozhttpd 23:28:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:28:00 INFO - Running setup.py install for mozinfo 23:28:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:28:00 INFO - Running setup.py install for mozInstall 23:28:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:28:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:28:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:28:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:28:01 INFO - Running setup.py install for mozleak 23:28:01 INFO - Running setup.py install for mozlog 23:28:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:28:01 INFO - Running setup.py install for moznetwork 23:28:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:28:01 INFO - Running setup.py install for mozprocess 23:28:01 INFO - Running setup.py install for mozprofile 23:28:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:28:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:28:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:28:01 INFO - Running setup.py install for mozrunner 23:28:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:28:01 INFO - Running setup.py install for mozscreenshot 23:28:01 INFO - Running setup.py install for moztest 23:28:01 INFO - Running setup.py install for mozversion 23:28:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:28:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:28:02 INFO - Cleaning up... 23:28:02 INFO - Return code: 0 23:28:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:28:02 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')]} 23:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:02 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')]} 23:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:02 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 0x7fe6d5390f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aa0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9b370>, '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 0x2a9e8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9ed80>, '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': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', '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 23:28:02 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 23:28:02 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 23:28:02 INFO - Using env: {'DISPLAY': ':0', 23:28:02 INFO - 'HOME': '/home/cltbld', 23:28:02 INFO - 'LANG': 'en_US.UTF-8', 23:28:02 INFO - 'LANGUAGE': 'en_US:en', 23:28:02 INFO - 'LOGNAME': 'cltbld', 23:28:02 INFO - 'MAIL': '/var/mail/cltbld', 23:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:02 INFO - 'MOZ_NO_REMOTE': '1', 23:28:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:02 INFO - 'NO_EM_RESTART': '1', 23:28:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:02 INFO - 'PWD': '/builds/slave/test', 23:28:02 INFO - 'SHELL': '/bin/bash', 23:28:02 INFO - 'SHLVL': '1', 23:28:02 INFO - 'TERM': 'linux', 23:28:02 INFO - 'TMOUT': '86400', 23:28:02 INFO - 'USER': 'cltbld', 23:28:02 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', 23:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:02 INFO - '_': '/tools/buildbot/bin/python'} 23:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:28:02 INFO - Running setup.py (path:/tmp/pip-mE7mPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:28:02 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)) 23:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:28:02 INFO - Running setup.py (path:/tmp/pip-pTPg15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:28:02 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)) 23:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:28:02 INFO - Running setup.py (path:/tmp/pip-lIbaTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:28:02 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)) 23:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:28:02 INFO - Running setup.py (path:/tmp/pip-BKJlgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:28:02 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)) 23:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:28:02 INFO - Running setup.py (path:/tmp/pip-Cjxkgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:28:02 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)) 23:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:28:02 INFO - Running setup.py (path:/tmp/pip-DFKiEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:28:02 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)) 23:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:28:02 INFO - Running setup.py (path:/tmp/pip-92KoHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:28:02 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)) 23:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:28:02 INFO - Running setup.py (path:/tmp/pip-VBj2YJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:28:02 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)) 23:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:28:02 INFO - Running setup.py (path:/tmp/pip-WMsAzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:28:02 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)) 23:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:28:02 INFO - Running setup.py (path:/tmp/pip-0z7xYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:28:03 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)) 23:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:28:03 INFO - Running setup.py (path:/tmp/pip-zhz2GQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:28:03 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)) 23:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:28:03 INFO - Running setup.py (path:/tmp/pip-5OAUKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:28:03 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)) 23:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:28:03 INFO - Running setup.py (path:/tmp/pip-SGiFYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:28:03 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)) 23:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:28:03 INFO - Running setup.py (path:/tmp/pip-R1t80s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:28:03 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)) 23:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:03 INFO - Running setup.py (path:/tmp/pip-6eV5Wq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:03 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)) 23:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:28:03 INFO - Running setup.py (path:/tmp/pip-qWDzuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:28:03 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)) 23:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:28:03 INFO - Running setup.py (path:/tmp/pip-OF_pow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:28:03 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)) 23:28:03 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)) 23:28:03 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)) 23:28:03 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)) 23:28:03 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)) 23:28:03 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)) 23:28:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:28:03 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 23:28:03 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 23:28:03 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 23:28:03 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 23:28:03 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 23:28:03 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 23:28:05 INFO - Downloading blessings-1.6.tar.gz 23:28:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:28:05 INFO - Installing collected packages: blessings 23:28:05 INFO - Running setup.py install for blessings 23:28:05 INFO - Successfully installed blessings 23:28:05 INFO - Cleaning up... 23:28:05 INFO - Return code: 0 23:28:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:28:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:28:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:28:05 INFO - Reading from file tmpfile_stdout 23:28:05 INFO - Current package versions: 23:28:05 INFO - argparse == 1.2.1 23:28:05 INFO - blessings == 1.6 23:28:05 INFO - blobuploader == 1.2.4 23:28:05 INFO - docopt == 0.6.1 23:28:05 INFO - functools32 == 3.2.3-2 23:28:05 INFO - jsonschema == 2.5.1 23:28:05 INFO - manifestparser == 1.1 23:28:05 INFO - mozInstall == 1.12 23:28:05 INFO - mozcrash == 0.17 23:28:05 INFO - mozdebug == 0.1 23:28:05 INFO - mozdevice == 0.48 23:28:05 INFO - mozfile == 1.2 23:28:05 INFO - mozhttpd == 0.7 23:28:05 INFO - mozinfo == 0.9 23:28:05 INFO - mozleak == 0.1 23:28:05 INFO - mozlog == 3.3 23:28:05 INFO - moznetwork == 0.27 23:28:05 INFO - mozprocess == 0.23 23:28:05 INFO - mozprofile == 0.28 23:28:05 INFO - mozrunner == 6.12 23:28:05 INFO - mozscreenshot == 0.1 23:28:05 INFO - mozsystemmonitor == 0.3 23:28:05 INFO - moztest == 0.7 23:28:05 INFO - mozversion == 1.4 23:28:05 INFO - psutil == 3.1.1 23:28:05 INFO - requests == 1.2.3 23:28:05 INFO - wsgiref == 0.1.2 23:28:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:05 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 0x7fe6d5390f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aa0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9b370>, '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 0x2a9e8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9ed80>, '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': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', '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 23:28:05 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 23:28:05 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 23:28:05 INFO - Using env: {'DISPLAY': ':0', 23:28:05 INFO - 'HOME': '/home/cltbld', 23:28:05 INFO - 'LANG': 'en_US.UTF-8', 23:28:05 INFO - 'LANGUAGE': 'en_US:en', 23:28:05 INFO - 'LOGNAME': 'cltbld', 23:28:05 INFO - 'MAIL': '/var/mail/cltbld', 23:28:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:05 INFO - 'MOZ_NO_REMOTE': '1', 23:28:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:05 INFO - 'NO_EM_RESTART': '1', 23:28:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:05 INFO - 'PWD': '/builds/slave/test', 23:28:05 INFO - 'SHELL': '/bin/bash', 23:28:05 INFO - 'SHLVL': '1', 23:28:05 INFO - 'TERM': 'linux', 23:28:05 INFO - 'TMOUT': '86400', 23:28:05 INFO - 'USER': 'cltbld', 23:28:05 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', 23:28:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:05 INFO - '_': '/tools/buildbot/bin/python'} 23:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:06 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)) 23:28:06 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)) 23:28:06 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)) 23:28:06 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)) 23:28:06 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)) 23:28:06 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)) 23:28:06 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)) 23:28:06 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)) 23:28:06 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)) 23:28:06 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)) 23:28:06 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)) 23:28:06 INFO - Cleaning up... 23:28:06 INFO - Return code: 0 23:28:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:28: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')]} 23:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28: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')]} 23:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6d5390f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aa0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9b370>, '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 0x2a9e8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9ed80>, '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': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', '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 23:28: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', 'jsonschema'] in /builds/slave/test/build 23:28: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 jsonschema 23:28:06 INFO - Using env: {'DISPLAY': ':0', 23:28:06 INFO - 'HOME': '/home/cltbld', 23:28:06 INFO - 'LANG': 'en_US.UTF-8', 23:28:06 INFO - 'LANGUAGE': 'en_US:en', 23:28:06 INFO - 'LOGNAME': 'cltbld', 23:28:06 INFO - 'MAIL': '/var/mail/cltbld', 23:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:06 INFO - 'MOZ_NO_REMOTE': '1', 23:28:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:06 INFO - 'NO_EM_RESTART': '1', 23:28:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:06 INFO - 'PWD': '/builds/slave/test', 23:28:06 INFO - 'SHELL': '/bin/bash', 23:28:06 INFO - 'SHLVL': '1', 23:28:06 INFO - 'TERM': 'linux', 23:28:06 INFO - 'TMOUT': '86400', 23:28:06 INFO - 'USER': 'cltbld', 23:28:06 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', 23:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:06 INFO - '_': '/tools/buildbot/bin/python'} 23:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:28:06 INFO - Cleaning up... 23:28:06 INFO - Return code: 0 23:28:06 INFO - Running post-action listener: _resource_record_post_action 23:28:06 INFO - Running post-action listener: _start_resource_monitoring 23:28:06 INFO - Starting resource monitoring. 23:28:06 INFO - [mozharness: 2016-08-17 06:28:06.361662Z] Finished create-virtualenv step (success) 23:28:06 INFO - [mozharness: 2016-08-17 06:28:06.362113Z] Running install step. 23:28:06 INFO - Running pre-action listener: _resource_record_pre_action 23:28:06 INFO - Running main action method: install 23:28:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:28:06 INFO - Reading from file tmpfile_stdout 23:28:06 INFO - Detecting whether we're running mozinstall >=1.0... 23:28:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:28:06 INFO - Reading from file tmpfile_stdout 23:28:06 INFO - Output received: 23:28:06 INFO - Usage: mozinstall [options] installer 23:28:06 INFO - Options: 23:28:06 INFO - -h, --help show this help message and exit 23:28:06 INFO - -d DEST, --destination=DEST 23:28:06 INFO - Directory to install application into. [default: 23:28:06 INFO - "/builds/slave/test"] 23:28:06 INFO - --app=APP Application being installed. [default: firefox] 23:28:06 INFO - mkdir: /builds/slave/test/build/application 23:28:06 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'] 23:28:06 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 23:28:23 INFO - Reading from file tmpfile_stdout 23:28:23 INFO - Output received: 23:28:23 INFO - /builds/slave/test/build/application/firefox/firefox 23:28:23 INFO - Running post-action listener: _resource_record_post_action 23:28:23 INFO - [mozharness: 2016-08-17 06:28:23.553602Z] Finished install step (success) 23:28:23 INFO - [mozharness: 2016-08-17 06:28:23.553853Z] Running run-tests step. 23:28:23 INFO - Running pre-action listener: _resource_record_pre_action 23:28:23 INFO - Running main action method: run_tests 23:28:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:28:23 INFO - Python 2.7.3 23:28:23 INFO - Return code: 0 23:28:23 INFO - grabbing minidump binary from tooltool 23:28:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:23 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 0x2a9b370>, '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 0x2a9e8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9ed80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:28:23 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 23:28:23 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 23:28:23 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 23:28:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:28:23 INFO - Return code: 0 23:28:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:28:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:28:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:28:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:28:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:28:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 23:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 23:28:23 INFO - Using env: {'DISPLAY': ':0', 23:28:23 INFO - 'HOME': '/home/cltbld', 23:28:23 INFO - 'LANG': 'en_US.UTF-8', 23:28:23 INFO - 'LANGUAGE': 'en_US:en', 23:28:23 INFO - 'LOGNAME': 'cltbld', 23:28:23 INFO - 'MAIL': '/var/mail/cltbld', 23:28:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:28:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:28:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:23 INFO - 'MOZ_NO_REMOTE': '1', 23:28:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:28:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:23 INFO - 'NO_EM_RESTART': '1', 23:28:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:23 INFO - 'PWD': '/builds/slave/test', 23:28:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:28:23 INFO - 'SHELL': '/bin/bash', 23:28:23 INFO - 'SHLVL': '1', 23:28:23 INFO - 'TERM': 'linux', 23:28:23 INFO - 'TMOUT': '86400', 23:28:23 INFO - 'USER': 'cltbld', 23:28:23 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010', 23:28:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:23 INFO - '_': '/tools/buildbot/bin/python'} 23:28:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 23:28:23 INFO - mozversion application_buildid: 20160816203016 23:28:23 INFO - mozversion application_changeset: d83220df469dca35a747895c64a7d8120e79e556 23:28:23 INFO - mozversion application_display_name: Nightly 23:28:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:28:23 INFO - mozversion application_name: Firefox 23:28:23 INFO - mozversion application_remotingname: firefox 23:28:23 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:28:23 INFO - mozversion application_vendor: Mozilla 23:28:23 INFO - mozversion application_version: 51.0a1 23:28:23 INFO - mozversion platform_buildid: 20160816203016 23:28:23 INFO - mozversion platform_changeset: d83220df469dca35a747895c64a7d8120e79e556 23:28:23 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:28:23 INFO - mozversion platform_version: 51.0a1 23:28:23 INFO - using testdate: 1471415303 23:28:23 INFO - actual date: 1471415303 23:28:23 INFO - starting webserver on 'localhost:35666' 23:28:23 INFO - SUITE-START | Running 2 tests 23:28:23 INFO - TEST-START | dromaeo_css 23:28:23 INFO - operating with platform_type : linux_ 23:28:23 INFO - Initialising browser for dromaeo_css test... 23:28:23 INFO - TEST-INFO | started process 15324 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg7RpPk/profile http://localhost:35666/getInfo.html) 23:28:27 INFO - PROCESS | 15324 | __metrics Screen width/height:1600/1200 23:28:27 INFO - PROCESS | 15324 | colorDepth:24 23:28:27 INFO - PROCESS | 15324 | Browser inner width/height: 1024/697 23:28:27 INFO - PROCESS | 15324 | __metrics 23:28:28 INFO - PROCESS | 15324 | 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" 23:28:28 INFO - PROCESS | 15324 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:28:28 INFO - PROCESS | 15324 | console.error: PushService: 23:28:28 INFO - PROCESS | 15324 | stateChangeProcessEnqueue: Error transitioning state 23:28:28 INFO - PROCESS | 15324 | UnknownError 23:28:29 INFO - TEST-INFO | 15324: exit 0 23:28:29 INFO - Browser initialized. 23:28:29 INFO - Running cycle 1/1 for dromaeo_css test... 23:28:29 INFO - TEST-INFO | started process 15405 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg7RpPk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:28:40 INFO - PROCESS | 15405 | RSS: Main: 154378240 23:28:40 INFO - PROCESS | 15405 | 23:29:32 INFO - PROCESS | 15405 | 1471415372430 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] 23:31:41 INFO - PROCESS | 15405 | Cycle 1(1): loaded http://localhost:35666/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35666/tests/dromaeo/cssquery-ext.html) 23:31:42 INFO - PROCESS | 15405 | RSS: Main: 257495040 23:31:42 INFO - PROCESS | 15405 | 23:34:45 INFO - PROCESS | 15405 | Cycle 1(1): loaded http://localhost:35666/tests/dromaeo/cssquery-ext.html (next: http://localhost:35666/tests/dromaeo/cssquery-jquery.html) 23:34:45 INFO - PROCESS | 15405 | RSS: Main: 238542848 23:34:45 INFO - PROCESS | 15405 | 23:37:48 INFO - PROCESS | 15405 | Cycle 1(1): loaded http://localhost:35666/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35666/tests/dromaeo/cssquery-mootools.html) 23:37:48 INFO - PROCESS | 15405 | RSS: Main: 214048768 23:37:48 INFO - PROCESS | 15405 | 23:40:50 INFO - PROCESS | 15405 | Cycle 1(1): loaded http://localhost:35666/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35666/tests/dromaeo/cssquery-prototype.html) 23:40:51 INFO - PROCESS | 15405 | RSS: Main: 210505728 23:40:51 INFO - PROCESS | 15405 | 23:43:54 INFO - PROCESS | 15405 | Cycle 1(1): loaded http://localhost:35666/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35666/tests/dromaeo/cssquery-yui.html) 23:43:54 INFO - PROCESS | 15405 | RSS: Main: 181346304 23:43:54 INFO - PROCESS | 15405 | 23:46:57 INFO - PROCESS | 15405 | Cycle 1(1): loaded http://localhost:35666/tests/dromaeo/cssquery-yui.html (next: http://localhost:35666/tests/dromaeo/cssquery-dojo.html) 23:46:58 INFO - PROCESS | 15405 | RSS: Main: 279543808 23:46:58 INFO - PROCESS | 15405 | 23:46:58 INFO - PROCESS | 15405 | __start_tp_report 23:46:58 INFO - PROCESS | 15405 | _x_x_mozilla_page_load 23:46:58 INFO - PROCESS | 15405 | _x_x_mozilla_page_load_details 23:46:58 INFO - PROCESS | 15405 | |i|pagename|runs| 23:46:58 INFO - PROCESS | 15405 | |0;dojo.html;3094.24191073187;3111.424386488501;3111.875524979001;3136.3727254549094;3139.717425431712;5326;5375.4355792641745;5387.380451248097;5457.890842183159;5474.2609747684;5382.354117505895;5399.7030163341;5407.270018547495;5455.990641731282;5466.371367292762;4891.633127515442;4959.057779021974;4970.950290497085;4982.775775090112;5116;5063.974680126595;5065.518775716319;5093.465186155456;5112.514311140454;5113.642045056848;4895.3146559481875;4936.160852655057;4977.975110124463;5001.7499125043605;5013.7242451665325;4897.240927656219;4901.289313049623;4912.70523768575;4915.557599816034;4974.726390048546;4940.678855874393;4979.551840334351;4990.725510096943;5003.424606170264;5007.298978143063;5262.105442074857;5401.405845357008;5410.3507579090765;5452.509274165304;5459.890802183973;5859.150423188614;5862.970685146547;5875.618084824473;5906.320773111104;5908.350081491032;5953.434423729739;5958.106284057383;5971.552133589998;5972;5974.701264936779;5274.208868669692;5369;5415.810446634349;5426.647267927611;5510.752016159283;5976.9402305977255;6033.879322413615;6035.094735789667;6035.969820150871;6035.969820150959;5709;5713.371529131792;5738.340090889517;5753.021986262345;5757.021306377842;5671.943280567224;5737.942620573825;5788.9710551447815;5808.30300363959;5893.528517718572;5754.367019627837;5812.738426770806;5813.854653633693;5815.88368232633;5823.155642431823;5731.369549349568;5768.596198266165;5774.191613174159;5788.88422231553;5808.883822323614;4369.104333611602;4395.538468460765;4398;4404.251277282806;4406.559344065632;3970.781607011584;4001.6598589119667;4004.0590461241586;4010.9197816043516;4051.47330846988;5508.669479831223;5509.807156749494;5511.53151982078;5511.641743286674;5547.55619550435;5684.8010319638615;5825.097109947965;5830.038043722739;5831.795887144014;5839.532837373;5476.452354764571;5531.3362396512675;5539.058360078796;5575.303087114111;5582.609217354828;5051.848444546669;5087.847364579068;5097.260897169964;5097.515736005148;5144.202648589475;5861.794837180763;5995.46040856325;6142.109394137825;6152.953997820381;6158.076288556663;5815.912761308668;5911.940880591139;5920.437558431942;5921.437463440966;5940.732667030081;6141.877162456815;6149;6158.876822463436;6159.322474527708;6167.105769663463;5654.434556544313;5681.0626246668835;5787.623804452697;5801.680907550125;5804.361520232856;5817.854553636193;5884.264466941632;5885.3820348862755;5931.377205393372;5936.22829032223;5724.942750572608;5768.134779783066;5784.855378615568;5785.247917770663;5786.537077033743;5556.583256255716;5567.944320556742;5679.602427830012;5740.13897915316;5740.282464691913;5553.305836770403;5616.438356164351;5633.887322253614;5667.0649342859615;5679.602427830012;5707.6575405475805;5714.542836573148;5745.8563535911935;5751.654900705971;5765.221695071112;5503.229547863222;5556.055470569932;5569.192467092168;5586.581006424453;5589.860253493533 23:46:58 INFO - PROCESS | 15405 | |1;ext.html;586.0944840221857;596.1326270276747;598.1356939222825;612.0818771842237;629.2323365494098;31363.215919601993;42421;43346;43437.91405214863;43467;43919;44430.11139777202;44614;44634.553654463445;44694.10611787762;8777.122287771235;8890.599923003449;8923.286137109048;8926.776830579223;8928.776780580472;8821.073787252342;8889.599968001423;8912.821743565124;8915.95542022289;8918.955405222998;8918.598663060146;8970.058143894896;8978.147076027766;8979.595918183697;8981.281497480219;8862.113788621118;8889.377743557954;8897.288216942628;8906.287497000225;8907.198352148305;8773.561321933941;8848.336374771918;8863.822723545492;8864.867026994536;8865.556722163867;42634.57365426337;43053.354199687026;43079;43086.78456607728;43096.3535546967;3686.207465394953;3703.8518459261595;3710.8515659373593;3770.3024940385844;3839.78881161536;32007.83996080028;32205;32207.35585288281;32495.675043249503;32698.346033079204;4114.115465174972;4137.97931010346;4173.165366926596;4197.811098500544;4215.15696860626;367378;368166;368584;368594.9999999973;369089;57130.714346428;57148.14277785837;57344;57486.42513574894;57517.13724294139;36257.81871090628;59395;59556.10665840016;59572;59586.70206648939;16539.338426463048;17370;17403.216855241546;17415.216315265843;17415.73876391855;13074.934625326812;13143.60569182926;13211.735765284833;13224;13256.933715331554;19284.065235983628;19411.857958148405;19427.863163113536;19432.102193835068;19501.731894732504;6984.371406573433;7214.537022229254;7243.637818109073;7382;7477.85044299111;950.5199874063553;1041.6979076068008;1088.4285749981166;1098.1599076706325;1176.5705517486226;33460.98777787134;33551.16122096967;33581.32837343239;33609;33854;1080.6866008857344;1149.9655010349702;1180.931690326816;1183.2368122561072;1226.172333674766;16396.344146234016;17313.91343043302;17361.4791556251;17380.82619173792;17585.7362139568;594773;595847;596471;597075;598218;59408.10887836686;59885;59969.70015149896;60093;60154;1216.160849014195;1236.7773800715777;1321.187469706124;1354.823872896517;1381.8272715910512;29974.134549985574;34351.31297374088;34392.65607343894;34658.653413465545;34855;49239.26141107958;49511.25733114078;49621;49635.01459941706;49882.50117498779;433378;436472;437571;438621;438624;25027.749722502544;27469;28208.71791282143;28270.15189544317;28625.570616441186;48354.274685880446;48508.757456212494;48525.757371212916;49252.26121608107;49674.254886176015;41012.79493602513;41665.79167104145;41965.1606967865;42157;43423.34864976965;40619.59380406158;41091.589084109975;41571;42325.15349693051;42845.57154428416;41717.58282417258;42209.788951055045;42291;42726.359104612835;42733.78633106814;34801.82599086988;36994;37292.25415491729;37900.62099379081;38237.617623823404;27627.86186069057;28653.856730716212;28897.56653650155;28986.56520152157;29030.854845725637 23:46:58 INFO - PROCESS | 15405 | |2;jquery.html;383.36170276489645;387.9049632839953;392.12360374562854;394.1565050791306;414.6392638404587;14113.717725645482;14401.207933563675;18451.815481845177;19193.904030479847;19215.903920480378;18377.081145942684;18937.810621893776;19556.11997460114;20309.289174878882;20468.795312046874;5565.6104072714925;5657.038303488417;5749.252597162363;5865.208196893429;5878.823635290927;5749.971250143743;5763;5795.681237531934;5799.507041901444;5801.767929282823;5543.445655434464;5695.715214239292;5736.6558006519745;5751.539876809866;5770.624909380897;5712.657240565559;5748.5401167906675;5753.6260143090985;5756.65460072397;5760.308762948431;4915.877103072452;5090.77091530882;5136.64043516954;5161.329027226474;5165.690058596496;23765.287041388612;24114.758852411425;24275.271741847773;24320.635190472156;24380.293379463445;2430;2488.9377765555823;2554.514642217973;2643.1806140096633;2880.337522369846;10241.948790256001;10652.41411722355;10657.094146997515;14855.182964936925;14959.92520037393;1913.2633935934625;1972.7829938706732;2000.6698894682506;2065.7624373197123;2080.0951586060114;57522.424775752545;66036.3396366035;73357.63321183414;83199;85720.57139714262;10234.795304093876;10240.948795256125;10668.093212077061;10705.411202383944;11173.089418703388;14505.492307769178;14670.193139377223;14683.77974330386;15197.772033419507;15199.544013679608;7783.299503044753;7893.763187104396;8300.83398332042;8557.144285571392;8604;7633.923660763432;7772.494787838772;8038.678452861937;8335.874961875577;8403.411761176782;4508.188526065274;4524.185646583581;4535.183666939983;4628.791704373311;4636.675432719677;1984.5227173002618;1987.6966367813811;2019.384087853219;2038.2865996901262;2065.617860695746;914.6524320758069;973.4354074636693;975.5609975510906;980.1081016275156;985.3939827006483;18406.35577754799;19566.21735130579;20703.689444658343;20764.273250436163;20779.792202077784;977.4868194198103;995.581855620641;1022.6574097677361;1046.5709059285657;1068.1027936533349;10615.097716694014;11520.769584608428;11525.711857203469;11538.59614913474;11544.88455115455;348077;351088;353462;497944;512320;89395.55302223447;95917.5204123975;95988.52005739926;98895.50552247337;100026;1007.5667462990984;1037.7820657661825;1066.9733256668646;1068.4657671164418;1072.0351683484926;17473.650526989135;17652.91173544124;18393.356232531798;18405.81594184041;18686.25254989785;35104;44171.55828441803;44580.777096115606;44896.77551612352;46365.768171158925;59700.70149649224;63004.68497657483;63052.369476306485;65324.67337663281;67764;8351.624176911931;8597.26923211509;8689.565521724015;8808.075152109193;8825.073367296342;11812.291262524275;13267.084467304265;13298.667533311746;13704.794428083387;13746.518871839438;11524.250923689933;11726.355050471964;11775.646730598095;11924.582639607572;12240.816387754012;11476.311421314747;11877.971117365016;11924.105692073303;11944.880551194725;11948.223365481215;11502.309861408343;11743.236689615147;11752.353620550952;11946.641600751991;12316.199447035913;7742.961285193538;7836.412269079728;7932.881006785018;8594.527300998505;8714.38999270045;8974.775630609287;11183.72040698989;11716.179867409199;11762.94118529402;11857.822132667845 23:46:58 INFO - PROCESS | 15405 | |3;mootools.html;1327.2036777933247;1445.4146070841307;1480.8148981377328;1606.9919650401746;1753.438899552143;8181.381957773515;8543.871841922368;8597.355198360125;8831.602577883994;8834.337424693142;8654.350923680719;8787.956060219705;8804.163604457566;8804.339674524404;8809.163129502704;2051.015512553975;2345.2143531916827;2345.7419683834764;2379.0364902214546;2387.6776635154292;2402.507485965373;2422.9273121806373;2435.1598698449006;2440.5606990741658;2442.780149786519;2324.883755812211;2385.1771138957033;2392.8444651097625;2394.0543485323287;2398.0647547456506;2259.1641092795608;2333.614953532665;2367;2375.061850569024;2376.3940195250275;2417.2748175547267;2438.0491608272787;2438.512297540499;2440.646106314592;2444.706635203787;8881.111888811067;8930.642774289021;8941.910580894173;8944.64221431142;8950.641974321019;4978.327925730016;5082.644214904958;5158.716270605115;5179.080381878704;5236.266922630796;3206.887758928426;3375.8143302118374;3429.2112814052907;3440.569928758905;3474.583050034012;1085.4193006741355;1094.0591091661165;2087.4155236533643;2176.183931025865;2205.933821985343;12043.518259269618;12171.2697238166;12263.26420414769;12277.815832762426;12280.07899407548;4198.6641068714425;4397.36238245459;4465.397171381886;4533.705309154894;4562.338460923214;9383.859242111237;9389.624415023323;9396.953015235016;9397.530123493798;9404.670836520689;5197.948020519746;5199.766010529535;5200.349956255468;5204.479552044841;5204.8178313758835;4331.371951067078;4787.281907713871;4861.683990540603;4916.213405855046;4920.237363208708;3822.3119838429348;3851.383778595411;3886.805659717003;3937.1888931091444;3982.283189026003;1134.7049767060623;1141.7944769941323;1144.3991904250336;1147.8852114788622;1151.948162332697;976.2580438866371;993.9900600993898;996.5366104761321;997.3317877022413;1021.3922715983978;5945.583809133405;6020;6108.847278817976;6141.662208578482;6476.906072966783;968.941863488193;1006.3408467453829;1012.2509343086064;1015.5988384588046;1042.551702767802;4018.578049304781;4077.7757223352405;4149.211649786531;4196.139791342767;4228.048689045002;10692.572297108185;11155.768846230787;11176.217664763552;11179.161562882653;11194.776104477865;11050.281731687415;11085.722856928642;11086.334819910831;11140.053095486812;11145.387003714874;7783.649735762017;8136.14570470104;8227.753167404988;8235.547044912588;8258.958705206436;6064.06007068905;6222.408871157233;6243.094751261133;6337.4613157882095;6359.300476947625;10070.295079344669;10103.393796372242;10145.54345054489;10189.032041956003;10291.176705863363;9777.80444391094;10526.421046842192;10532.631357902437;10533.894661053599;10558.630447934285;7036.9296307036275;7513.887291690519;7701.037370328709;7819.100803407453;7952.6421311039985;8632.870506942527;8697.95651021741;8766.123387661182;8786.121387861163;8793.032766395563;7555.357794587521;7555.773326800205;7604.505707129019;7690.884636730565;7848.411369147223;7521.05986751656;7624.085109786861;7644.235576442311;7683.692652294069;7851.293383595504;6756.594604323756;6874.243833178246;6880.621565813928;6885.345892140239;6889.758858439931;4883.6093112551625;4891.241857512091;5039.3448851649055;5088.949110508847;5099.949000509948;5148.407933087593;5181.844544663666;5259.289995850664;5333.466653334635;5362.919556206738 23:46:58 INFO - PROCESS | 15405 | |4;prototype.html;304.1271550649635;308.2125170190164;313.79760054764677;330.5917192267548;330.7916012911865;5357.598180136486;5364.846557990031;5439.456054394564;5442.727863606814;5472.20653005314;5020.924686129711;5104.6937183769;5132.281480592711;5145.6398052136365;5321.547668448186;3972.741771784832;3990.5211374635082;4084.81618327176;4109.9178016439655;4111.259973204822;4091.754494730311;4099.549049604541;4104.486939132608;4116;4129.483814523184;3857.6720978716703;4009.7995100245016;4068.3490641497365;4089.9591004090025;4108.732932359402;4062.289099407592;4068.1253530491085;4072.898177545585;4074.246264441088;4086.8773936781795;3543.7419716000704;3567.6967457766123;3569.8215089245696;3574.1779390740276;3579.337822502819;4951.207806750938;5247.47525247476;5254.789808407659;5259.237410575483;5263.26314315996;4618.745968971705;4621.65337599678;4624.421947256593;4630.305454181866;4671.462781780071;5484.149956756709;5501.422350653163;5510.8071217507595;5522.392536820947;5524.281843360378;3802.4866643003393;3856.8842934712;3862.343401621731;3870.3226935286493;3888.1640447303685;5689.236336735667;5694.572907031948;5716.657000579979;5726.427357264281;5767.625104368245;3878.8836334909997;3878.9224215516097;3888.055972013993;3890.20250848581;3890.9416358754643;3852.788096654655;3908.8241029153755;3941.4971493102084;3949.54580223278;3952.9209415811524;4436.760022457038;4526.773661316987;4569.817207311737;4575.633949284116;4588.95411045892;5465.863353416117;5483.917741233885;5484.643498172606;5500.4048825840055;5510.91733623996;1285.203174032106;1373.3133433283358;1384.0173476831362;1384.1625816380945;1388.1462900316435;903.1375036839802;920.7007722490217;935.6958988328685;939.3800091939287;941.1576638908276;5376.91934620981;5402.567794576425;5403.162509810985;5414.7021913795115;5425.348958125051;5681.0910254359105;5687.744051517691;5700.657960522382;5723.484886360165;5724.627899186539;5245.737713114329;5458.754356053987;5460.426655201147;5465.660913076322;5491.313585801775;3013.9095827124784;3017.23060619545;3031.6058912341255;3059.877604895824;3071.094027261963;5668.744906479218;5876.971529982236;5906.873525530806;5921.555883308769;5943.257092863392;5747.942520574825;5752.36723960364;5777.162311464814;5788.884222315614;5799.3040835100055;5891.410858914074;5891.911621325684;5907.586468947218;5911.970440147772;5912.23140991677;5462.562994960314;5639.182318563868;5676.971615141898;5683.460071293221;5706.457886500776;5581.246531718329;5619.690916999604;5656.3495198051105;5661.2923384576925;5664.376918538875;5738.167965644875;5830.591858569859;5839.270091238595;5905.881882362414;5912.408759124053;3962.088719594452;3987.302222111177;4002.9599704002585;4021.9597804021587;4034.636882680573;5790.855228619318;5793.8551536211935;5809.65142091459;5826.563007774349;5828.125781132864;5358.758855851418;5560.249366335493;5612.186232996274;5622.493975542221;5703.828885133453;5554.861128471821;5562.443755624567;5591.384947655673;5601.579881508985;5614.9719251403485;5288.487968215067;5346;5508.173773933942;5518.42056584069;5567.8886422272135;5559.749811258423;5566.997940370772;5572.944270557405;5587.888242235052;5598.552115830806;5421.972890135524;5436.374816895949;5475.206095116107;5477.260569823182;5479.835604931858 23:46:58 INFO - PROCESS | 15405 | |5;yui.html;802.39018346057;813.5606772342935;846.8814365988759;850.7575341027808;876.3296078499944;15116.226788363829;17040.147992600367;17354.47936561902;17360.47918562442;17364.739528907077;15377.38490460383;15519.146446945413;15714;15728.685426291497;15731.528054158365;819.5001049359901;823.6952327638782;827.5862068965517;828.1676914700718;828.4780588229407;670.3330186464475;682.8736683713504;829.4484168028284;834.9187801796688;835.052215735142;725.0538047847557;726.6330503095929;850.8085680721819;866.9133086691312;870.1037930931151;860.7675927499603;861.2679222660751;862.211076864668;862.6851199312268;863.218786997768;631.5610650597837;631.5957787016313;632.2034236861588;632.3328888023134;632.822809613309;11068.723281917935;14903;14942.850571494255;14993.625159370993;15079.924600377037;14953.252337383088;15591.37634494619;15738.370465181379;16092.436764713297;16252.43116490917;12165.265491143628;12347.567835125818;12608.180468269533;12666.318745495682;12727.554535591209;3716.7584107033226;3727.1241258304276;3734.663880250763;3742.981285093584;3903.063264816452;84185;84768.57615711882;85444.57277713635;85700.14299856983;90272.54863725639;11140.33158010522;11398.316101033965;12029.699257518632;12088.153829232046;12430.56493022758;23980.52038959211;24020.51958960846;24062.51874962526;24109.15617953363;24157.275281741575;8403.07566167721;8591.097934716765;8719.60761765722;8815.867761983576;8832.955835220782;4891.144049791344;4915.877103072452;5042.042012017705;5162.483751624883;5263;3202.1834432219634;3303.5044743288217;3316.4859446785786;3326.284848757529;3327.5341452196712;578.3522454850511;582.8193260089301;596.5376832954495;602.1118849696662;603.1917230910601;614.0451597765428;668.9597675614466;707.1973310292752;733.6405161470841;736.3777607921298;9217.677381291756;9512.191463725523;10267.486625668687;10289.125424338865;10595.152387808957;676.3067855448106;707.5789905006513;741.8627553902545;784.7449578886884;799.6521513141802;4517.412736344254;4784.258439941815;4929.285253638202;4977.303177555145;5012.122878496249;127952.36023819822;129283;129741.56679815782;130056.3497182508;131878;105281.47359263155;106165.98045538003;106548;106782;110672;77589.61205193937;98013;98693.50653246831;100074;100236;9951.651692190739;10283.43441110746;10303.896961030294;10323.587056517656;10618.203634727271;49767;51702.48297516977;53088.99999999846;54174;55127.724361377936;65777;73732.26267737254;78556;81045.59477202577;81052.00537900507;12589.874101259236;12935.935320323339;13050.869491304966;13236.867631323563;13441;14826.174850871676;15049.623759406102;15102.697946040798;15186.924065379602;16398.590035248737;13600.183988960694;13719.862801371859;14067.41193589541;14119.505817296345;14656.413743450143;13451.394687238902;13779.862201377859;14236.857631423552;14286.571402857931;14581.85418145805;12727;12829.166104203197;13367.131136476099;13547.458101675822;13577.52478663242;11503.53985840557;11617;11818.527258909548;11855.881441185478;12049.759004820029;11657.437985747478;11894.702632434259;12121.333326667118;12438.37808109609;12462.813057803958 23:46:58 INFO - PROCESS | 15405 | __end_tp_report 23:46:58 INFO - PROCESS | 15405 | __start_cc_report 23:46:58 INFO - PROCESS | 15405 | _x_x_mozilla_cycle_collect,936 23:46:58 INFO - PROCESS | 15405 | __end_cc_report 23:46:58 INFO - PROCESS | 15405 | __startTimestamp1471416418188__endTimestamp 23:46:58 INFO - PROCESS | 15405 | 23:46:58 INFO - PROCESS | 15405 | ------- Summary: start ------- 23:46:58 INFO - PROCESS | 15405 | Number of tests: 6 23:46:58 INFO - PROCESS | 15405 | 23:46:58 INFO - PROCESS | 15405 | [#0] dojo.html Cycles:165 Average:5427.89 Median:5589.86 stddev:626.34 (11.2%) stddev-sans-first:600.91 23:46:58 INFO - PROCESS | 15405 | Values: 3094.2 3111.4 3111.9 3136.4 3139.7 5326.0 5375.4 5387.4 5457.9 5474.3 5382.4 5399.7 5407.3 5456.0 5466.4 4891.6 4959.1 4971.0 4982.8 5116.0 5064.0 5065.5 5093.5 5112.5 5113.6 4895.3 4936.2 4978.0 5001.7 5013.7 4897.2 4901.3 4912.7 4915.6 4974.7 4940.7 4979.6 4990.7 5003.4 5007.3 5262.1 5401.4 5410.4 5452.5 5459.9 5859.2 5863.0 5875.6 5906.3 5908.4 5953.4 5958.1 5971.6 5972.0 5974.7 5274.2 5369.0 5415.8 5426.6 5510.8 5976.9 6033.9 6035.1 6036.0 6036.0 5709.0 5713.4 5738.3 5753.0 5757.0 5671.9 5737.9 5789.0 5808.3 5893.5 5754.4 5812.7 5813.9 5815.9 5823.2 5731.4 5768.6 5774.2 5788.9 5808.9 4369.1 4395.5 4398.0 4404.3 4406.6 3970.8 4001.7 4004.1 4010.9 4051.5 5508.7 5509.8 5511.5 5511.6 5547.6 5684.8 5825.1 5830.0 5831.8 5839.5 5476.5 5531.3 5539.1 5575.3 5582.6 5051.8 5087.8 5097.3 5097.5 5144.2 5861.8 5995.5 6142.1 6153.0 6158.1 5815.9 5911.9 5920.4 5921.4 5940.7 6141.9 6149.0 6158.9 6159.3 6167.1 5654.4 5681.1 5787.6 5801.7 5804.4 5817.9 5884.3 5885.4 5931.4 5936.2 5724.9 5768.1 5784.9 5785.2 5786.5 5556.6 5567.9 5679.6 5740.1 5740.3 5553.3 5616.4 5633.9 5667.1 5679.6 5707.7 5714.5 5745.9 5751.7 5765.2 5503.2 5556.1 5569.2 5586.6 5589.9 23:46:58 INFO - PROCESS | 15405 | 23:46:58 INFO - PROCESS | 15405 | [#1] ext.html Cycles:180 Average:62506.95 Median:42308.08 stddev:126762.11 (299.6%) stddev-sans-first:127032.46 23:46:58 INFO - PROCESS | 15405 | Values: 586.1 596.1 598.1 612.1 629.2 31363.2 42421.0 43346.0 43437.9 43467.0 43919.0 44430.1 44614.0 44634.6 44694.1 8777.1 8890.6 8923.3 8926.8 8928.8 8821.1 8889.6 8912.8 8916.0 8919.0 8918.6 8970.1 8978.1 8979.6 8981.3 8862.1 8889.4 8897.3 8906.3 8907.2 8773.6 8848.3 8863.8 8864.9 8865.6 42634.6 43053.4 43079.0 43086.8 43096.4 3686.2 3703.9 3710.9 3770.3 3839.8 32007.8 32205.0 32207.4 32495.7 32698.3 4114.1 4138.0 4173.2 4197.8 4215.2 367378.0 368166.0 368584.0 368595.0 369089.0 57130.7 57148.1 57344.0 57486.4 57517.1 36257.8 59395.0 59556.1 59572.0 59586.7 16539.3 17370.0 17403.2 17415.2 17415.7 13074.9 13143.6 13211.7 13224.0 13256.9 19284.1 19411.9 19427.9 19432.1 19501.7 6984.4 7214.5 7243.6 7382.0 7477.9 950.5 1041.7 1088.4 1098.2 1176.6 33461.0 33551.2 33581.3 33609.0 33854.0 1080.7 1150.0 1180.9 1183.2 1226.2 16396.3 17313.9 17361.5 17380.8 17585.7 594773.0 595847.0 596471.0 597075.0 598218.0 59408.1 59885.0 59969.7 60093.0 60154.0 1216.2 1236.8 1321.2 1354.8 1381.8 29974.1 34351.3 34392.7 34658.7 34855.0 49239.3 49511.3 49621.0 49635.0 49882.5 433378.0 436472.0 437571.0 438621.0 438624.0 25027.7 27469.0 28208.7 28270.2 28625.6 48354.3 48508.8 48525.8 49252.3 49674.3 41012.8 41665.8 41965.2 42157.0 43423.3 40619.6 41091.6 41571.0 42325.2 42845.6 41717.6 42209.8 42291.0 42726.4 42733.8 34801.8 36994.0 37292.3 37900.6 38237.6 27627.9 28653.9 28897.6 28986.6 29030.9 23:46:58 INFO - PROCESS | 15405 | 23:46:58 INFO - PROCESS | 15405 | [#2] jquery.html Cycles:180 Average:27004.63 Median:2598.85 stddev:69745.70 (2683.7%) stddev-sans-first:69912.71 23:46:58 INFO - PROCESS | 15405 | Values: 383.4 387.9 392.1 394.2 414.6 14113.7 14401.2 18451.8 19193.9 19215.9 18377.1 18937.8 19556.1 20309.3 20468.8 5565.6 5657.0 5749.3 5865.2 5878.8 5750.0 5763.0 5795.7 5799.5 5801.8 5543.4 5695.7 5736.7 5751.5 5770.6 5712.7 5748.5 5753.6 5756.7 5760.3 4915.9 5090.8 5136.6 5161.3 5165.7 23765.3 24114.8 24275.3 24320.6 24380.3 2430.0 2488.9 2554.5 2643.2 2880.3 10241.9 10652.4 10657.1 14855.2 14959.9 1913.3 1972.8 2000.7 2065.8 2080.1 57522.4 66036.3 73357.6 83199.0 85720.6 10234.8 10240.9 10668.1 10705.4 11173.1 14505.5 14670.2 14683.8 15197.8 15199.5 7783.3 7893.8 8300.8 8557.1 8604.0 7633.9 7772.5 8038.7 8335.9 8403.4 4508.2 4524.2 4535.2 4628.8 4636.7 1984.5 1987.7 2019.4 2038.3 2065.6 914.7 973.4 975.6 980.1 985.4 18406.4 19566.2 20703.7 20764.3 20779.8 977.5 995.6 1022.7 1046.6 1068.1 10615.1 11520.8 11525.7 11538.6 11544.9 348077.0 351088.0 353462.0 497944.0 512320.0 89395.6 95917.5 95988.5 98895.5 100026.0 1007.6 1037.8 1067.0 1068.5 1072.0 17473.7 17652.9 18393.4 18405.8 18686.3 35104.0 44171.6 44580.8 44896.8 46365.8 59700.7 63004.7 63052.4 65324.7 67764.0 8351.6 8597.3 8689.6 8808.1 8825.1 11812.3 13267.1 13298.7 13704.8 13746.5 11524.3 11726.4 11775.6 11924.6 12240.8 11476.3 11878.0 11924.1 11944.9 11948.2 11502.3 11743.2 11752.4 11946.6 12316.2 7743.0 7836.4 7932.9 8594.5 8714.4 8974.8 11183.7 11716.2 11762.9 11857.8 23:46:58 INFO - PROCESS | 15405 | 23:46:58 INFO - PROCESS | 15405 | [#3] mootools.html Cycles:180 Average:5809.46 Median:4824.48 stddev:3262.17 (67.6%) stddev-sans-first:3253.92 23:46:58 INFO - PROCESS | 15405 | Values: 1327.2 1445.4 1480.8 1607.0 1753.4 8181.4 8543.9 8597.4 8831.6 8834.3 8654.4 8788.0 8804.2 8804.3 8809.2 2051.0 2345.2 2345.7 2379.0 2387.7 2402.5 2422.9 2435.2 2440.6 2442.8 2324.9 2385.2 2392.8 2394.1 2398.1 2259.2 2333.6 2367.0 2375.1 2376.4 2417.3 2438.0 2438.5 2440.6 2444.7 8881.1 8930.6 8941.9 8944.6 8950.6 4978.3 5082.6 5158.7 5179.1 5236.3 3206.9 3375.8 3429.2 3440.6 3474.6 1085.4 1094.1 2087.4 2176.2 2205.9 12043.5 12171.3 12263.3 12277.8 12280.1 4198.7 4397.4 4465.4 4533.7 4562.3 9383.9 9389.6 9397.0 9397.5 9404.7 5197.9 5199.8 5200.3 5204.5 5204.8 4331.4 4787.3 4861.7 4916.2 4920.2 3822.3 3851.4 3886.8 3937.2 3982.3 1134.7 1141.8 1144.4 1147.9 1151.9 976.3 994.0 996.5 997.3 1021.4 5945.6 6020.0 6108.8 6141.7 6476.9 968.9 1006.3 1012.3 1015.6 1042.6 4018.6 4077.8 4149.2 4196.1 4228.0 10692.6 11155.8 11176.2 11179.2 11194.8 11050.3 11085.7 11086.3 11140.1 11145.4 7783.6 8136.1 8227.8 8235.5 8259.0 6064.1 6222.4 6243.1 6337.5 6359.3 10070.3 10103.4 10145.5 10189.0 10291.2 9777.8 10526.4 10532.6 10533.9 10558.6 7036.9 7513.9 7701.0 7819.1 7952.6 8632.9 8698.0 8766.1 8786.1 8793.0 7555.4 7555.8 7604.5 7690.9 7848.4 7521.1 7624.1 7644.2 7683.7 7851.3 6756.6 6874.2 6880.6 6885.3 6889.8 4883.6 4891.2 5039.3 5088.9 5099.9 5148.4 5181.8 5259.3 5333.5 5362.9 23:46:58 INFO - PROCESS | 15405 | 23:46:58 INFO - PROCESS | 15405 | [#4] prototype.html Cycles:180 Average:4650.58 Median:5423.66 stddev:1395.66 (25.7%) stddev-sans-first:1360.91 23:46:58 INFO - PROCESS | 15405 | Values: 304.1 308.2 313.8 330.6 330.8 5357.6 5364.8 5439.5 5442.7 5472.2 5020.9 5104.7 5132.3 5145.6 5321.5 3972.7 3990.5 4084.8 4109.9 4111.3 4091.8 4099.5 4104.5 4116.0 4129.5 3857.7 4009.8 4068.3 4090.0 4108.7 4062.3 4068.1 4072.9 4074.2 4086.9 3543.7 3567.7 3569.8 3574.2 3579.3 4951.2 5247.5 5254.8 5259.2 5263.3 4618.7 4621.7 4624.4 4630.3 4671.5 5484.1 5501.4 5510.8 5522.4 5524.3 3802.5 3856.9 3862.3 3870.3 3888.2 5689.2 5694.6 5716.7 5726.4 5767.6 3878.9 3878.9 3888.1 3890.2 3890.9 3852.8 3908.8 3941.5 3949.5 3952.9 4436.8 4526.8 4569.8 4575.6 4589.0 5465.9 5483.9 5484.6 5500.4 5510.9 1285.2 1373.3 1384.0 1384.2 1388.1 903.1 920.7 935.7 939.4 941.2 5376.9 5402.6 5403.2 5414.7 5425.3 5681.1 5687.7 5700.7 5723.5 5724.6 5245.7 5458.8 5460.4 5465.7 5491.3 3013.9 3017.2 3031.6 3059.9 3071.1 5668.7 5877.0 5906.9 5921.6 5943.3 5747.9 5752.4 5777.2 5788.9 5799.3 5891.4 5891.9 5907.6 5912.0 5912.2 5462.6 5639.2 5677.0 5683.5 5706.5 5581.2 5619.7 5656.3 5661.3 5664.4 5738.2 5830.6 5839.3 5905.9 5912.4 3962.1 3987.3 4003.0 4022.0 4034.6 5790.9 5793.9 5809.7 5826.6 5828.1 5358.8 5560.2 5612.2 5622.5 5703.8 5554.9 5562.4 5591.4 5601.6 5615.0 5288.5 5346.0 5508.2 5518.4 5567.9 5559.7 5567.0 5572.9 5587.9 5598.6 5422.0 5436.4 5475.2 5477.3 5479.8 23:46:58 INFO - PROCESS | 15405 | 23:46:58 INFO - PROCESS | 15405 | [#5] yui.html Cycles:180 Average:22324.09 Median:3310.00 stddev:32986.29 (996.6%) stddev-sans-first:33039.24 23:46:58 INFO - PROCESS | 15405 | Values: 802.4 813.6 846.9 850.8 876.3 15116.2 17040.1 17354.5 17360.5 17364.7 15377.4 15519.1 15714.0 15728.7 15731.5 819.5 823.7 827.6 828.2 828.5 670.3 682.9 829.4 834.9 835.1 725.1 726.6 850.8 866.9 870.1 860.8 861.3 862.2 862.7 863.2 631.6 631.6 632.2 632.3 632.8 11068.7 14903.0 14942.9 14993.6 15079.9 14953.3 15591.4 15738.4 16092.4 16252.4 12165.3 12347.6 12608.2 12666.3 12727.6 3716.8 3727.1 3734.7 3743.0 3903.1 84185.0 84768.6 85444.6 85700.1 90272.5 11140.3 11398.3 12029.7 12088.2 12430.6 23980.5 24020.5 24062.5 24109.2 24157.3 8403.1 8591.1 8719.6 8815.9 8833.0 4891.1 4915.9 5042.0 5162.5 5263.0 3202.2 3303.5 3316.5 3326.3 3327.5 578.4 582.8 596.5 602.1 603.2 614.0 669.0 707.2 733.6 736.4 9217.7 9512.2 10267.5 10289.1 10595.2 676.3 707.6 741.9 784.7 799.7 4517.4 4784.3 4929.3 4977.3 5012.1 127952.4 129283.0 129741.6 130056.3 131878.0 105281.5 106166.0 106548.0 106782.0 110672.0 77589.6 98013.0 98693.5 100074.0 100236.0 9951.7 10283.4 10303.9 10323.6 10618.2 49767.0 51702.5 53089.0 54174.0 55127.7 65777.0 73732.3 78556.0 81045.6 81052.0 12589.9 12935.9 13050.9 13236.9 13441.0 14826.2 15049.6 15102.7 15186.9 16398.6 13600.2 13719.9 14067.4 14119.5 14656.4 13451.4 13779.9 14236.9 14286.6 14581.9 12727.0 12829.2 13367.1 13547.5 13577.5 11503.5 11617.0 11818.5 11855.9 12049.8 11657.4 11894.7 12121.3 12438.4 12462.8 23:46:58 INFO - PROCESS | 15405 | -------- Summary: end -------- 23:46:58 INFO - PROCESS | 15405 | 23:46:58 INFO - TEST-INFO | 15405: exit 0 23:46:58 INFO - TEST-OK | dromaeo_css | took 1115149ms 23:46:58 INFO - TEST-START | kraken 23:46:58 INFO - operating with platform_type : linux_ 23:46:58 INFO - Initialising browser for kraken test... 23:46:58 INFO - TEST-INFO | started process 15711 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiT7lha/profile http://localhost:35666/getInfo.html) 23:47:02 INFO - PROCESS | 15711 | __metrics Screen width/height:1600/1200 23:47:02 INFO - PROCESS | 15711 | colorDepth:24 23:47:02 INFO - PROCESS | 15711 | Browser inner width/height: 1024/697 23:47:02 INFO - PROCESS | 15711 | __metrics 23:47:03 INFO - PROCESS | 15711 | 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" 23:47:03 INFO - PROCESS | 15711 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:47:04 INFO - TEST-INFO | 15711: exit 0 23:47:04 INFO - Browser initialized. 23:47:04 INFO - Running cycle 1/1 for kraken test... 23:47:04 INFO - TEST-INFO | started process 15796 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiT7lha/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:47:15 INFO - PROCESS | 15796 | RSS: Main: 154583040 23:47:15 INFO - PROCESS | 15796 | 23:47:18 INFO - PROCESS | 15796 | Cycle 1(1): loaded http://localhost:35666/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35666/tests/kraken/driver.html?testName=audio-beat-detection) 23:47:19 INFO - PROCESS | 15796 | RSS: Main: 202928128 23:47:19 INFO - PROCESS | 15796 | 23:47:21 INFO - PROCESS | 15796 | Cycle 1(1): loaded http://localhost:35666/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35666/tests/kraken/driver.html?testName=audio-dft) 23:47:22 INFO - PROCESS | 15796 | RSS: Main: 193998848 23:47:22 INFO - PROCESS | 15796 | 23:47:25 INFO - PROCESS | 15796 | Cycle 1(1): loaded http://localhost:35666/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35666/tests/kraken/driver.html?testName=audio-fft) 23:47:26 INFO - PROCESS | 15796 | RSS: Main: 274866176 23:47:26 INFO - PROCESS | 15796 | 23:47:28 INFO - PROCESS | 15796 | Cycle 1(1): loaded http://localhost:35666/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35666/tests/kraken/driver.html?testName=audio-oscillator) 23:47:28 INFO - PROCESS | 15796 | RSS: Main: 184623104 23:47:28 INFO - PROCESS | 15796 | 23:47:31 INFO - PROCESS | 15796 | Cycle 1(1): loaded http://localhost:35666/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35666/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:47:31 INFO - PROCESS | 15796 | RSS: Main: 189489152 23:47:31 INFO - PROCESS | 15796 | 23:47:34 INFO - PROCESS | 15796 | Cycle 1(1): loaded http://localhost:35666/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35666/tests/kraken/driver.html?testName=imaging-darkroom) 23:47:35 INFO - PROCESS | 15796 | RSS: Main: 224608256 23:47:35 INFO - PROCESS | 15796 | 23:47:38 INFO - PROCESS | 15796 | Cycle 1(1): loaded http://localhost:35666/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35666/tests/kraken/driver.html?testName=imaging-desaturate) 23:47:39 INFO - PROCESS | 15796 | RSS: Main: 225275904 23:47:39 INFO - PROCESS | 15796 | 23:47:42 INFO - PROCESS | 15796 | Cycle 1(1): loaded http://localhost:35666/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35666/tests/kraken/driver.html?testName=json-parse-financial) 23:47:42 INFO - PROCESS | 15796 | RSS: Main: 225058816 23:47:42 INFO - PROCESS | 15796 | 23:47:44 INFO - PROCESS | 15796 | Cycle 1(1): loaded http://localhost:35666/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35666/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:47:44 INFO - PROCESS | 15796 | RSS: Main: 187883520 23:47:44 INFO - PROCESS | 15796 | 23:47:46 INFO - PROCESS | 15796 | Cycle 1(1): loaded http://localhost:35666/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35666/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:47:47 INFO - PROCESS | 15796 | RSS: Main: 195346432 23:47:47 INFO - PROCESS | 15796 | 23:47:49 INFO - PROCESS | 15796 | Cycle 1(1): loaded http://localhost:35666/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35666/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:47:49 INFO - PROCESS | 15796 | RSS: Main: 192020480 23:47:49 INFO - PROCESS | 15796 | 23:47:52 INFO - PROCESS | 15796 | Cycle 1(1): loaded http://localhost:35666/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35666/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:47:53 INFO - PROCESS | 15796 | RSS: Main: 186220544 23:47:53 INFO - PROCESS | 15796 | 23:47:55 INFO - PROCESS | 15796 | Cycle 1(1): loaded http://localhost:35666/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35666/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:47:56 INFO - PROCESS | 15796 | RSS: Main: 190877696 23:47:56 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | Cycle 1(1): loaded http://localhost:35666/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35666/tests/kraken/driver.html?testName=ai-astar) 23:47:58 INFO - PROCESS | 15796 | RSS: Main: 240971776 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | __start_tp_report 23:47:58 INFO - PROCESS | 15796 | _x_x_mozilla_page_load 23:47:58 INFO - PROCESS | 15796 | _x_x_mozilla_page_load_details 23:47:58 INFO - PROCESS | 15796 | |i|pagename|runs| 23:47:58 INFO - PROCESS | 15796 | |0;ai-astar;104;111;113;113;107;111;113;112;112;110 23:47:58 INFO - PROCESS | 15796 | |1;audio-beat-detection;149;116;119;177;117;123;140;117;152;124 23:47:58 INFO - PROCESS | 15796 | |2;audio-dft;160;162;161;158;160;161;159;159;161;159 23:47:58 INFO - PROCESS | 15796 | |3;audio-fft;77;75;75;110;75;76;75;78;106;77 23:47:58 INFO - PROCESS | 15796 | |4;audio-oscillator;84;85;130;84;85;84;116;83;85;141 23:47:58 INFO - PROCESS | 15796 | |5;imaging-gaussian-blur;117;117;116;117;116;116;117;117;117;117 23:47:58 INFO - PROCESS | 15796 | |6;imaging-darkroom;146;146;146;145;146;146;146;145;146;146 23:47:58 INFO - PROCESS | 15796 | |7;imaging-desaturate;95;96;95;96;95;95;95;95;95;95 23:47:58 INFO - PROCESS | 15796 | |8;json-parse-financial;50;49;51;50;50;52;51;51;51;49 23:47:58 INFO - PROCESS | 15796 | |9;json-stringify-tinderbox;62;62;61;62;62;62;61;61;61;61 23:47:58 INFO - PROCESS | 15796 | |10;stanford-crypto-aes;85;83;78;78;78;79;77;83;83;79 23:47:58 INFO - PROCESS | 15796 | |11;stanford-crypto-ccm;131;121;130;121;122;120;128;122;148;123 23:47:58 INFO - PROCESS | 15796 | |12;stanford-crypto-pbkdf2;161;164;165;160;163;164;164;162;158;161 23:47:58 INFO - PROCESS | 15796 | |13;stanford-crypto-sha256-iterative;65;65;64;64;63;63;65;70;64;67 23:47:58 INFO - PROCESS | 15796 | __end_tp_report 23:47:58 INFO - PROCESS | 15796 | __start_cc_report 23:47:58 INFO - PROCESS | 15796 | _x_x_mozilla_cycle_collect,1621 23:47:58 INFO - PROCESS | 15796 | __end_cc_report 23:47:58 INFO - PROCESS | 15796 | __startTimestamp1471416478341__endTimestamp 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | ------- Summary: start ------- 23:47:58 INFO - PROCESS | 15796 | Number of tests: 14 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | [#0] ai-astar Cycles:10 Average:110.60 Median:112.00 stddev:2.95 (2.6%) stddev-sans-first:1.94 23:47:58 INFO - PROCESS | 15796 | Values: 104.0 111.0 113.0 113.0 107.0 111.0 113.0 112.0 112.0 110.0 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | [#1] audio-beat-detection Cycles:10 Average:133.40 Median:132.00 stddev:20.49 (15.5%) stddev-sans-first:20.94 23:47:58 INFO - PROCESS | 15796 | Values: 149.0 116.0 119.0 177.0 117.0 123.0 140.0 117.0 152.0 124.0 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.50 stddev:1.25 (0.8%) stddev-sans-first:1.32 23:47:58 INFO - PROCESS | 15796 | Values: 160.0 162.0 161.0 158.0 160.0 161.0 159.0 159.0 161.0 159.0 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | [#3] audio-fft Cycles:10 Average:82.40 Median:75.50 stddev:13.57 (18.0%) stddev-sans-first:14.25 23:47:58 INFO - PROCESS | 15796 | Values: 77.0 75.0 75.0 110.0 75.0 76.0 75.0 78.0 106.0 77.0 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:84.00 stddev:22.40 (26.7%) stddev-sans-first:23.20 23:47:58 INFO - PROCESS | 15796 | Values: 84.0 85.0 130.0 84.0 85.0 84.0 116.0 83.0 85.0 141.0 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 23:47:58 INFO - PROCESS | 15796 | Values: 117.0 117.0 116.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 23:47:58 INFO - PROCESS | 15796 | Values: 146.0 146.0 146.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 23:47:58 INFO - PROCESS | 15796 | Values: 95.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:51.00 stddev:0.97 (1.9%) stddev-sans-first:1.01 23:47:58 INFO - PROCESS | 15796 | Values: 50.0 49.0 51.0 50.0 50.0 52.0 51.0 51.0 51.0 49.0 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:62.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 23:47:58 INFO - PROCESS | 15796 | Values: 62.0 62.0 61.0 62.0 62.0 62.0 61.0 61.0 61.0 61.0 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:81.00 stddev:2.87 (3.5%) stddev-sans-first:2.49 23:47:58 INFO - PROCESS | 15796 | Values: 85.0 83.0 78.0 78.0 78.0 79.0 77.0 83.0 83.0 79.0 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | [#11] stanford-crypto-ccm Cycles:10 Average:126.60 Median:125.50 stddev:8.51 (6.8%) stddev-sans-first:8.88 23:47:58 INFO - PROCESS | 15796 | Values: 131.0 121.0 130.0 121.0 122.0 120.0 128.0 122.0 148.0 123.0 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.20 Median:163.50 stddev:2.20 (1.3%) stddev-sans-first:2.29 23:47:58 INFO - PROCESS | 15796 | Values: 161.0 164.0 165.0 160.0 163.0 164.0 164.0 162.0 158.0 161.0 23:47:58 INFO - PROCESS | 15796 | 23:47:58 INFO - PROCESS | 15796 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.00 stddev:2.11 (3.2%) stddev-sans-first:2.24 23:47:58 INFO - PROCESS | 15796 | Values: 65.0 65.0 64.0 64.0 63.0 63.0 65.0 70.0 64.0 67.0 23:47:58 INFO - PROCESS | 15796 | -------- Summary: end -------- 23:47:58 INFO - PROCESS | 15796 | 23:47:59 INFO - TEST-INFO | 15796: exit 0 23:47:59 INFO - TEST-OK | kraken | took 60418ms 23:47:59 INFO - SUITE-END | took 1175s 23:47:59 INFO - Completed test suite (00:19:35) 23:47:59 INFO - javascript benchmark 23:47:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3094.24191073187, 3111.424386488501, 3111.875524979001, 3136.3727254549094, 3139.717425431712, 5326.0, 5375.4355792641745, 5387.380451248097, 5457.890842183159, 5474.2609747684, 5382.354117505895, 5399.7030163341, 5407.270018547495, 5455.990641731282, 5466.371367292762, 4891.633127515442, 4959.057779021974, 4970.950290497085, 4982.775775090112, 5116.0, 5063.974680126595, 5065.518775716319, 5093.465186155456, 5112.514311140454, 5113.642045056848, 4895.3146559481875, 4936.160852655057, 4977.975110124463, 5001.7499125043605, 5013.7242451665325, 4897.240927656219, 4901.289313049623, 4912.70523768575, 4915.557599816034, 4974.726390048546, 4940.678855874393, 4979.551840334351, 4990.725510096943, 5003.424606170264, 5007.298978143063, 5262.105442074857, 5401.405845357008, 5410.3507579090765, 5452.509274165304, 5459.890802183973, 5859.150423188614, 5862.970685146547, 5875.618084824473, 5906.320773111104, 5908.350081491032, 5953.434423729739, 5958.106284057383, 5971.552133589998, 5972.0, 5974.701264936779, 5274.208868669692, 5369.0, 5415.810446634349, 5426.647267927611, 5510.752016159283, 5976.9402305977255, 6033.879322413615, 6035.094735789667, 6035.969820150871, 6035.969820150959, 5709.0, 5713.371529131792, 5738.340090889517, 5753.021986262345, 5757.021306377842, 5671.943280567224, 5737.942620573825, 5788.9710551447815, 5808.30300363959, 5893.528517718572, 5754.367019627837, 5812.738426770806, 5813.854653633693, 5815.88368232633, 5823.155642431823, 5731.369549349568, 5768.596198266165, 5774.191613174159, 5788.88422231553, 5808.883822323614, 4369.104333611602, 4395.538468460765, 4398.0, 4404.251277282806, 4406.559344065632, 3970.781607011584, 4001.6598589119667, 4004.0590461241586, 4010.9197816043516, 4051.47330846988, 5508.669479831223, 5509.807156749494, 5511.53151982078, 5511.641743286674, 5547.55619550435, 5684.8010319638615, 5825.097109947965, 5830.038043722739, 5831.795887144014, 5839.532837373, 5476.452354764571, 5531.3362396512675, 5539.058360078796, 5575.303087114111, 5582.609217354828, 5051.848444546669, 5087.847364579068, 5097.260897169964, 5097.515736005148, 5144.202648589475, 5861.794837180763, 5995.46040856325, 6142.109394137825, 6152.953997820381, 6158.076288556663, 5815.912761308668, 5911.940880591139, 5920.437558431942, 5921.437463440966, 5940.732667030081, 6141.877162456815, 6149.0, 6158.876822463436, 6159.322474527708, 6167.105769663463, 5654.434556544313, 5681.0626246668835, 5787.623804452697, 5801.680907550125, 5804.361520232856, 5817.854553636193, 5884.264466941632, 5885.3820348862755, 5931.377205393372, 5936.22829032223, 5724.942750572608, 5768.134779783066, 5784.855378615568, 5785.247917770663, 5786.537077033743, 5556.583256255716, 5567.944320556742, 5679.602427830012, 5740.13897915316, 5740.282464691913, 5553.305836770403, 5616.438356164351, 5633.887322253614, 5667.0649342859615, 5679.602427830012, 5707.6575405475805, 5714.542836573148, 5745.8563535911935, 5751.654900705971, 5765.221695071112, 5503.229547863222, 5556.055470569932, 5569.192467092168, 5586.581006424453, 5589.860253493533], "value": 5384.75447213251, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.0944840221857, 596.1326270276747, 598.1356939222825, 612.0818771842237, 629.2323365494098, 31363.215919601993, 42421.0, 43346.0, 43437.91405214863, 43467.0, 43919.0, 44430.11139777202, 44614.0, 44634.553654463445, 44694.10611787762, 8777.122287771235, 8890.599923003449, 8923.286137109048, 8926.776830579223, 8928.776780580472, 8821.073787252342, 8889.599968001423, 8912.821743565124, 8915.95542022289, 8918.955405222998, 8918.598663060146, 8970.058143894896, 8978.147076027766, 8979.595918183697, 8981.281497480219, 8862.113788621118, 8889.377743557954, 8897.288216942628, 8906.287497000225, 8907.198352148305, 8773.561321933941, 8848.336374771918, 8863.822723545492, 8864.867026994536, 8865.556722163867, 42634.57365426337, 43053.354199687026, 43079.0, 43086.78456607728, 43096.3535546967, 3686.207465394953, 3703.8518459261595, 3710.8515659373593, 3770.3024940385844, 3839.78881161536, 32007.83996080028, 32205.0, 32207.35585288281, 32495.675043249503, 32698.346033079204, 4114.115465174972, 4137.97931010346, 4173.165366926596, 4197.811098500544, 4215.15696860626, 367378.0, 368166.0, 368584.0, 368594.9999999973, 369089.0, 57130.714346428, 57148.14277785837, 57344.0, 57486.42513574894, 57517.13724294139, 36257.81871090628, 59395.0, 59556.10665840016, 59572.0, 59586.70206648939, 16539.338426463048, 17370.0, 17403.216855241546, 17415.216315265843, 17415.73876391855, 13074.934625326812, 13143.60569182926, 13211.735765284833, 13224.0, 13256.933715331554, 19284.065235983628, 19411.857958148405, 19427.863163113536, 19432.102193835068, 19501.731894732504, 6984.371406573433, 7214.537022229254, 7243.637818109073, 7382.0, 7477.85044299111, 950.5199874063553, 1041.6979076068008, 1088.4285749981166, 1098.1599076706325, 1176.5705517486226, 33460.98777787134, 33551.16122096967, 33581.32837343239, 33609.0, 33854.0, 1080.6866008857344, 1149.9655010349702, 1180.931690326816, 1183.2368122561072, 1226.172333674766, 16396.344146234016, 17313.91343043302, 17361.4791556251, 17380.82619173792, 17585.7362139568, 594773.0, 595847.0, 596471.0, 597075.0, 598218.0, 59408.10887836686, 59885.0, 59969.70015149896, 60093.0, 60154.0, 1216.160849014195, 1236.7773800715777, 1321.187469706124, 1354.823872896517, 1381.8272715910512, 29974.134549985574, 34351.31297374088, 34392.65607343894, 34658.653413465545, 34855.0, 49239.26141107958, 49511.25733114078, 49621.0, 49635.01459941706, 49882.50117498779, 433378.0, 436472.0, 437571.0, 438621.0, 438624.0, 25027.749722502544, 27469.0, 28208.71791282143, 28270.15189544317, 28625.570616441186, 48354.274685880446, 48508.757456212494, 48525.757371212916, 49252.26121608107, 49674.254886176015, 41012.79493602513, 41665.79167104145, 41965.1606967865, 42157.0, 43423.34864976965, 40619.59380406158, 41091.589084109975, 41571.0, 42325.15349693051, 42845.57154428416, 41717.58282417258, 42209.788951055045, 42291.0, 42726.359104612835, 42733.78633106814, 34801.82599086988, 36994.0, 37292.25415491729, 37900.62099379081, 38237.617623823404, 27627.86186069057, 28653.856730716212, 28897.56653650155, 28986.56520152157, 29030.854845725637], "value": 20347.279716072044, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.36170276489645, 387.9049632839953, 392.12360374562854, 394.1565050791306, 414.6392638404587, 14113.717725645482, 14401.207933563675, 18451.815481845177, 19193.904030479847, 19215.903920480378, 18377.081145942684, 18937.810621893776, 19556.11997460114, 20309.289174878882, 20468.795312046874, 5565.6104072714925, 5657.038303488417, 5749.252597162363, 5865.208196893429, 5878.823635290927, 5749.971250143743, 5763.0, 5795.681237531934, 5799.507041901444, 5801.767929282823, 5543.445655434464, 5695.715214239292, 5736.6558006519745, 5751.539876809866, 5770.624909380897, 5712.657240565559, 5748.5401167906675, 5753.6260143090985, 5756.65460072397, 5760.308762948431, 4915.877103072452, 5090.77091530882, 5136.64043516954, 5161.329027226474, 5165.690058596496, 23765.287041388612, 24114.758852411425, 24275.271741847773, 24320.635190472156, 24380.293379463445, 2430.0, 2488.9377765555823, 2554.514642217973, 2643.1806140096633, 2880.337522369846, 10241.948790256001, 10652.41411722355, 10657.094146997515, 14855.182964936925, 14959.92520037393, 1913.2633935934625, 1972.7829938706732, 2000.6698894682506, 2065.7624373197123, 2080.0951586060114, 57522.424775752545, 66036.3396366035, 73357.63321183414, 83199.0, 85720.57139714262, 10234.795304093876, 10240.948795256125, 10668.093212077061, 10705.411202383944, 11173.089418703388, 14505.492307769178, 14670.193139377223, 14683.77974330386, 15197.772033419507, 15199.544013679608, 7783.299503044753, 7893.763187104396, 8300.83398332042, 8557.144285571392, 8604.0, 7633.923660763432, 7772.494787838772, 8038.678452861937, 8335.874961875577, 8403.411761176782, 4508.188526065274, 4524.185646583581, 4535.183666939983, 4628.791704373311, 4636.675432719677, 1984.5227173002618, 1987.6966367813811, 2019.384087853219, 2038.2865996901262, 2065.617860695746, 914.6524320758069, 973.4354074636693, 975.5609975510906, 980.1081016275156, 985.3939827006483, 18406.35577754799, 19566.21735130579, 20703.689444658343, 20764.273250436163, 20779.792202077784, 977.4868194198103, 995.581855620641, 1022.6574097677361, 1046.5709059285657, 1068.1027936533349, 10615.097716694014, 11520.769584608428, 11525.711857203469, 11538.59614913474, 11544.88455115455, 348077.0, 351088.0, 353462.0, 497944.0, 512320.0, 89395.55302223447, 95917.5204123975, 95988.52005739926, 98895.50552247337, 100026.0, 1007.5667462990984, 1037.7820657661825, 1066.9733256668646, 1068.4657671164418, 1072.0351683484926, 17473.650526989135, 17652.91173544124, 18393.356232531798, 18405.81594184041, 18686.25254989785, 35104.0, 44171.55828441803, 44580.777096115606, 44896.77551612352, 46365.768171158925, 59700.70149649224, 63004.68497657483, 63052.369476306485, 65324.67337663281, 67764.0, 8351.624176911931, 8597.26923211509, 8689.565521724015, 8808.075152109193, 8825.073367296342, 11812.291262524275, 13267.084467304265, 13298.667533311746, 13704.794428083387, 13746.518871839438, 11524.250923689933, 11726.355050471964, 11775.646730598095, 11924.582639607572, 12240.816387754012, 11476.311421314747, 11877.971117365016, 11924.105692073303, 11944.880551194725, 11948.223365481215, 11502.309861408343, 11743.236689615147, 11752.353620550952, 11946.641600751991, 12316.199447035913, 7742.961285193538, 7836.412269079728, 7932.881006785018, 8594.527300998505, 8714.38999270045, 8974.775630609287, 11183.72040698989, 11716.179867409199, 11762.94118529402, 11857.822132667845], "value": 9262.777563640726, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.2036777933247, 1445.4146070841307, 1480.8148981377328, 1606.9919650401746, 1753.438899552143, 8181.381957773515, 8543.871841922368, 8597.355198360125, 8831.602577883994, 8834.337424693142, 8654.350923680719, 8787.956060219705, 8804.163604457566, 8804.339674524404, 8809.163129502704, 2051.015512553975, 2345.2143531916827, 2345.7419683834764, 2379.0364902214546, 2387.6776635154292, 2402.507485965373, 2422.9273121806373, 2435.1598698449006, 2440.5606990741658, 2442.780149786519, 2324.883755812211, 2385.1771138957033, 2392.8444651097625, 2394.0543485323287, 2398.0647547456506, 2259.1641092795608, 2333.614953532665, 2367.0, 2375.061850569024, 2376.3940195250275, 2417.2748175547267, 2438.0491608272787, 2438.512297540499, 2440.646106314592, 2444.706635203787, 8881.111888811067, 8930.642774289021, 8941.910580894173, 8944.64221431142, 8950.641974321019, 4978.327925730016, 5082.644214904958, 5158.716270605115, 5179.080381878704, 5236.266922630796, 3206.887758928426, 3375.8143302118374, 3429.2112814052907, 3440.569928758905, 3474.583050034012, 1085.4193006741355, 1094.0591091661165, 2087.4155236533643, 2176.183931025865, 2205.933821985343, 12043.518259269618, 12171.2697238166, 12263.26420414769, 12277.815832762426, 12280.07899407548, 4198.6641068714425, 4397.36238245459, 4465.397171381886, 4533.705309154894, 4562.338460923214, 9383.859242111237, 9389.624415023323, 9396.953015235016, 9397.530123493798, 9404.670836520689, 5197.948020519746, 5199.766010529535, 5200.349956255468, 5204.479552044841, 5204.8178313758835, 4331.371951067078, 4787.281907713871, 4861.683990540603, 4916.213405855046, 4920.237363208708, 3822.3119838429348, 3851.383778595411, 3886.805659717003, 3937.1888931091444, 3982.283189026003, 1134.7049767060623, 1141.7944769941323, 1144.3991904250336, 1147.8852114788622, 1151.948162332697, 976.2580438866371, 993.9900600993898, 996.5366104761321, 997.3317877022413, 1021.3922715983978, 5945.583809133405, 6020.0, 6108.847278817976, 6141.662208578482, 6476.906072966783, 968.941863488193, 1006.3408467453829, 1012.2509343086064, 1015.5988384588046, 1042.551702767802, 4018.578049304781, 4077.7757223352405, 4149.211649786531, 4196.139791342767, 4228.048689045002, 10692.572297108185, 11155.768846230787, 11176.217664763552, 11179.161562882653, 11194.776104477865, 11050.281731687415, 11085.722856928642, 11086.334819910831, 11140.053095486812, 11145.387003714874, 7783.649735762017, 8136.14570470104, 8227.753167404988, 8235.547044912588, 8258.958705206436, 6064.06007068905, 6222.408871157233, 6243.094751261133, 6337.4613157882095, 6359.300476947625, 10070.295079344669, 10103.393796372242, 10145.54345054489, 10189.032041956003, 10291.176705863363, 9777.80444391094, 10526.421046842192, 10532.631357902437, 10533.894661053599, 10558.630447934285, 7036.9296307036275, 7513.887291690519, 7701.037370328709, 7819.100803407453, 7952.6421311039985, 8632.870506942527, 8697.95651021741, 8766.123387661182, 8786.121387861163, 8793.032766395563, 7555.357794587521, 7555.773326800205, 7604.505707129019, 7690.884636730565, 7848.411369147223, 7521.05986751656, 7624.085109786861, 7644.235576442311, 7683.692652294069, 7851.293383595504, 6756.594604323756, 6874.243833178246, 6880.621565813928, 6885.345892140239, 6889.758858439931, 4883.6093112551625, 4891.241857512091, 5039.3448851649055, 5088.949110508847, 5099.949000509948, 5148.407933087593, 5181.844544663666, 5259.289995850664, 5333.466653334635, 5362.919556206738], "value": 4698.723536455249, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.1271550649635, 308.2125170190164, 313.79760054764677, 330.5917192267548, 330.7916012911865, 5357.598180136486, 5364.846557990031, 5439.456054394564, 5442.727863606814, 5472.20653005314, 5020.924686129711, 5104.6937183769, 5132.281480592711, 5145.6398052136365, 5321.547668448186, 3972.741771784832, 3990.5211374635082, 4084.81618327176, 4109.9178016439655, 4111.259973204822, 4091.754494730311, 4099.549049604541, 4104.486939132608, 4116.0, 4129.483814523184, 3857.6720978716703, 4009.7995100245016, 4068.3490641497365, 4089.9591004090025, 4108.732932359402, 4062.289099407592, 4068.1253530491085, 4072.898177545585, 4074.246264441088, 4086.8773936781795, 3543.7419716000704, 3567.6967457766123, 3569.8215089245696, 3574.1779390740276, 3579.337822502819, 4951.207806750938, 5247.47525247476, 5254.789808407659, 5259.237410575483, 5263.26314315996, 4618.745968971705, 4621.65337599678, 4624.421947256593, 4630.305454181866, 4671.462781780071, 5484.149956756709, 5501.422350653163, 5510.8071217507595, 5522.392536820947, 5524.281843360378, 3802.4866643003393, 3856.8842934712, 3862.343401621731, 3870.3226935286493, 3888.1640447303685, 5689.236336735667, 5694.572907031948, 5716.657000579979, 5726.427357264281, 5767.625104368245, 3878.8836334909997, 3878.9224215516097, 3888.055972013993, 3890.20250848581, 3890.9416358754643, 3852.788096654655, 3908.8241029153755, 3941.4971493102084, 3949.54580223278, 3952.9209415811524, 4436.760022457038, 4526.773661316987, 4569.817207311737, 4575.633949284116, 4588.95411045892, 5465.863353416117, 5483.917741233885, 5484.643498172606, 5500.4048825840055, 5510.91733623996, 1285.203174032106, 1373.3133433283358, 1384.0173476831362, 1384.1625816380945, 1388.1462900316435, 903.1375036839802, 920.7007722490217, 935.6958988328685, 939.3800091939287, 941.1576638908276, 5376.91934620981, 5402.567794576425, 5403.162509810985, 5414.7021913795115, 5425.348958125051, 5681.0910254359105, 5687.744051517691, 5700.657960522382, 5723.484886360165, 5724.627899186539, 5245.737713114329, 5458.754356053987, 5460.426655201147, 5465.660913076322, 5491.313585801775, 3013.9095827124784, 3017.23060619545, 3031.6058912341255, 3059.877604895824, 3071.094027261963, 5668.744906479218, 5876.971529982236, 5906.873525530806, 5921.555883308769, 5943.257092863392, 5747.942520574825, 5752.36723960364, 5777.162311464814, 5788.884222315614, 5799.3040835100055, 5891.410858914074, 5891.911621325684, 5907.586468947218, 5911.970440147772, 5912.23140991677, 5462.562994960314, 5639.182318563868, 5676.971615141898, 5683.460071293221, 5706.457886500776, 5581.246531718329, 5619.690916999604, 5656.3495198051105, 5661.2923384576925, 5664.376918538875, 5738.167965644875, 5830.591858569859, 5839.270091238595, 5905.881882362414, 5912.408759124053, 3962.088719594452, 3987.302222111177, 4002.9599704002585, 4021.9597804021587, 4034.636882680573, 5790.855228619318, 5793.8551536211935, 5809.65142091459, 5826.563007774349, 5828.125781132864, 5358.758855851418, 5560.249366335493, 5612.186232996274, 5622.493975542221, 5703.828885133453, 5554.861128471821, 5562.443755624567, 5591.384947655673, 5601.579881508985, 5614.9719251403485, 5288.487968215067, 5346.0, 5508.173773933942, 5518.42056584069, 5567.8886422272135, 5559.749811258423, 5566.997940370772, 5572.944270557405, 5587.888242235052, 5598.552115830806, 5421.972890135524, 5436.374816895949, 5475.206095116107, 5477.260569823182, 5479.835604931858], "value": 4200.3812278692185, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [802.39018346057, 813.5606772342935, 846.8814365988759, 850.7575341027808, 876.3296078499944, 15116.226788363829, 17040.147992600367, 17354.47936561902, 17360.47918562442, 17364.739528907077, 15377.38490460383, 15519.146446945413, 15714.0, 15728.685426291497, 15731.528054158365, 819.5001049359901, 823.6952327638782, 827.5862068965517, 828.1676914700718, 828.4780588229407, 670.3330186464475, 682.8736683713504, 829.4484168028284, 834.9187801796688, 835.052215735142, 725.0538047847557, 726.6330503095929, 850.8085680721819, 866.9133086691312, 870.1037930931151, 860.7675927499603, 861.2679222660751, 862.211076864668, 862.6851199312268, 863.218786997768, 631.5610650597837, 631.5957787016313, 632.2034236861588, 632.3328888023134, 632.822809613309, 11068.723281917935, 14903.0, 14942.850571494255, 14993.625159370993, 15079.924600377037, 14953.252337383088, 15591.37634494619, 15738.370465181379, 16092.436764713297, 16252.43116490917, 12165.265491143628, 12347.567835125818, 12608.180468269533, 12666.318745495682, 12727.554535591209, 3716.7584107033226, 3727.1241258304276, 3734.663880250763, 3742.981285093584, 3903.063264816452, 84185.0, 84768.57615711882, 85444.57277713635, 85700.14299856983, 90272.54863725639, 11140.33158010522, 11398.316101033965, 12029.699257518632, 12088.153829232046, 12430.56493022758, 23980.52038959211, 24020.51958960846, 24062.51874962526, 24109.15617953363, 24157.275281741575, 8403.07566167721, 8591.097934716765, 8719.60761765722, 8815.867761983576, 8832.955835220782, 4891.144049791344, 4915.877103072452, 5042.042012017705, 5162.483751624883, 5263.0, 3202.1834432219634, 3303.5044743288217, 3316.4859446785786, 3326.284848757529, 3327.5341452196712, 578.3522454850511, 582.8193260089301, 596.5376832954495, 602.1118849696662, 603.1917230910601, 614.0451597765428, 668.9597675614466, 707.1973310292752, 733.6405161470841, 736.3777607921298, 9217.677381291756, 9512.191463725523, 10267.486625668687, 10289.125424338865, 10595.152387808957, 676.3067855448106, 707.5789905006513, 741.8627553902545, 784.7449578886884, 799.6521513141802, 4517.412736344254, 4784.258439941815, 4929.285253638202, 4977.303177555145, 5012.122878496249, 127952.36023819822, 129283.0, 129741.56679815782, 130056.3497182508, 131878.0, 105281.47359263155, 106165.98045538003, 106548.0, 106782.0, 110672.0, 77589.61205193937, 98013.0, 98693.50653246831, 100074.0, 100236.0, 9951.651692190739, 10283.43441110746, 10303.896961030294, 10323.587056517656, 10618.203634727271, 49767.0, 51702.48297516977, 53088.99999999846, 54174.0, 55127.724361377936, 65777.0, 73732.26267737254, 78556.0, 81045.59477202577, 81052.00537900507, 12589.874101259236, 12935.935320323339, 13050.869491304966, 13236.867631323563, 13441.0, 14826.174850871676, 15049.623759406102, 15102.697946040798, 15186.924065379602, 16398.590035248737, 13600.183988960694, 13719.862801371859, 14067.41193589541, 14119.505817296345, 14656.413743450143, 13451.394687238902, 13779.862201377859, 14236.857631423552, 14286.571402857931, 14581.85418145805, 12727.0, 12829.166104203197, 13367.131136476099, 13547.458101675822, 13577.52478663242, 11503.53985840557, 11617.0, 11818.527258909548, 11855.881441185478, 12049.759004820029, 11657.437985747478, 11894.702632434259, 12121.333326667118, 12438.37808109609, 12462.813057803958], "value": 7812.168682238598, "unit": "score"}], "name": "dromaeo_css", "value": 7340.9089627809435, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 113.0, 113.0, 107.0, 111.0, 113.0, 112.0, 112.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 119.0, 177.0, 117.0, 123.0, 140.0, 117.0, 152.0, 124.0], "value": 133.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 161.0, 158.0, 160.0, 161.0, 159.0, 159.0, 161.0, 159.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 75.0, 110.0, 75.0, 76.0, 75.0, 78.0, 106.0, 77.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 130.0, 84.0, 85.0, 84.0, 116.0, 83.0, 85.0, 141.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 51.0, 50.0, 50.0, 52.0, 51.0, 51.0, 51.0, 49.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 62.0, 62.0, 62.0, 61.0, 61.0, 61.0, 61.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 78.0, 78.0, 78.0, 79.0, 77.0, 83.0, 83.0, 79.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 121.0, 130.0, 121.0, 122.0, 120.0, 128.0, 122.0, 148.0, 123.0], "value": 126.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 165.0, 160.0, 163.0, 164.0, 164.0, 162.0, 158.0, 161.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 64.0, 63.0, 63.0, 65.0, 70.0, 64.0, 67.0], "value": 65.0, "unit": "score"}], "name": "kraken", "value": 1487.8000000000002, "alertThreshold": 2.0}]} 23:47:59 INFO - Return code: 0 23:47:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:47:59 INFO - # TBPL SUCCESS # 23:47:59 INFO - Running post-action listener: _resource_record_post_action 23:47:59 INFO - [mozharness: 2016-08-17 06:47:59.643352Z] Finished run-tests step (success) 23:47:59 INFO - Running post-run listener: _resource_record_post_run 23:47:59 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 8192; Write bytes: 426385408; Read time: 88; Write time: 260240 23:47:59 INFO - TinderboxPrint: CPU usage
13.0% 23:47:59 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 88 23:47:59 INFO - TinderboxPrint: I/O write bytes / time
426,385,408 / 260,240 23:47:59 INFO - TinderboxPrint: CPU idle
8,284.5 (87.0%) 23:47:59 INFO - TinderboxPrint: CPU user
1,194.6 (12.5%) 23:47:59 INFO - TinderboxPrint: Swap in / out
0 / 0 23:47:59 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 133877760; Read time: 0; Write time: 79444 23:47:59 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291192832; Read time: 76; Write time: 180736 23:48:00 INFO - Running post-run listener: _upload_blobber_files 23:48:00 INFO - Blob upload gear active. 23:48:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:48:00 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 23:48:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:48:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:48:00 INFO - (blobuploader) - INFO - Open directory for files ... 23:48:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:48:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:48:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:48:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:48:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:48:09 INFO - (blobuploader) - INFO - Done attempting. 23:48:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:48:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:48:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:48:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:48:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:48:09 INFO - (blobuploader) - INFO - Done attempting. 23:48:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:48:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:48:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:48:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:48:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:48:10 INFO - (blobuploader) - INFO - Done attempting. 23:48:10 INFO - (blobuploader) - INFO - Iteration through files over. 23:48:10 INFO - Return code: 0 23:48:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:48:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:48:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eef4ac7e63459c7a764efdb91b29ba054ce69c9866e055bf0f772b7bd339ff833754519dc39a0f77f65b6e88468f83e97836f6489fc83b99ec4aa43bf018ab4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8f0039cf6c46242aef5452fcfdca25bae04f03bfc962fd7d9c0e9d30f4ff01614d4187490f11a5cebbf78ca65b17e749a7da91ac3d3dca3c86522748db75af46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a80dc7105bc7ac6ee3c55918d86e31cbfeb5eb81bff246b13d87ed42e78bebe2aba3331acf4696e7bf69a353921ccd6d541fa22eb9e41a7a0e87fe6a3d09982"} 23:48:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:48:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:48:10 INFO - Contents: 23:48:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eef4ac7e63459c7a764efdb91b29ba054ce69c9866e055bf0f772b7bd339ff833754519dc39a0f77f65b6e88468f83e97836f6489fc83b99ec4aa43bf018ab4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8f0039cf6c46242aef5452fcfdca25bae04f03bfc962fd7d9c0e9d30f4ff01614d4187490f11a5cebbf78ca65b17e749a7da91ac3d3dca3c86522748db75af46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a80dc7105bc7ac6ee3c55918d86e31cbfeb5eb81bff246b13d87ed42e78bebe2aba3331acf4696e7bf69a353921ccd6d541fa22eb9e41a7a0e87fe6a3d09982"} 23:48:10 INFO - Running post-run listener: copy_logs_to_upload_dir 23:48:10 INFO - Copying logs to upload dir... 23:48:10 INFO - mkdir: /builds/slave/test/build/upload/logs 23:48:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.204831 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-16 23:48:10.447728) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 23:48:10.450766) ========= 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=1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eef4ac7e63459c7a764efdb91b29ba054ce69c9866e055bf0f772b7bd339ff833754519dc39a0f77f65b6e88468f83e97836f6489fc83b99ec4aa43bf018ab4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8f0039cf6c46242aef5452fcfdca25bae04f03bfc962fd7d9c0e9d30f4ff01614d4187490f11a5cebbf78ca65b17e749a7da91ac3d3dca3c86522748db75af46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a80dc7105bc7ac6ee3c55918d86e31cbfeb5eb81bff246b13d87ed42e78bebe2aba3331acf4696e7bf69a353921ccd6d541fa22eb9e41a7a0e87fe6a3d09982"} build_url:https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011486 build_url: 'https://queue.taskcluster.net/v1/task/T41HOIoaT0aZVOZAyp4Gaw/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/Fx-Team/sha512/eef4ac7e63459c7a764efdb91b29ba054ce69c9866e055bf0f772b7bd339ff833754519dc39a0f77f65b6e88468f83e97836f6489fc83b99ec4aa43bf018ab4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8f0039cf6c46242aef5452fcfdca25bae04f03bfc962fd7d9c0e9d30f4ff01614d4187490f11a5cebbf78ca65b17e749a7da91ac3d3dca3c86522748db75af46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a80dc7105bc7ac6ee3c55918d86e31cbfeb5eb81bff246b13d87ed42e78bebe2aba3331acf4696e7bf69a353921ccd6d541fa22eb9e41a7a0e87fe6a3d09982"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 23:48:10.477204) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 23:48:10.477548) ========= 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=1f5564757af27664ca02d71f000001b0-1471413610.125665-890410010 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004533 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 23:48:10.558181) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 23:48:10.561618) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 23:48:10.562004) ========= ========= Total master_lag: 0.17 =========