builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1470769649.7 results: success (0) buildid: 20160809083002 builduid: 31d038f94ce24eaf9cd0f58e7c63cfd8 revision: 613fec9a571e48c64918ebd0a8153096300ff992 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 12:07:29.697302) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 12:07:29.697877) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 12:07:29.698283) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 12:07:29.741102) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 12:07:29.741404) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-09 12:07:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 68.8M=0s 2016-08-09 12:07:30 (68.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111894 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 12:07:29.873210) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 12:07:29.873530) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015051 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 12:07:29.923118) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 12:07:29.923477) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 613fec9a571e48c64918ebd0a8153096300ff992 --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 613fec9a571e48c64918ebd0a8153096300ff992 --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=9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-09 12:07:30,411 truncating revision to first 12 chars 2016-08-09 12:07:30,411 Setting DEBUG logging. 2016-08-09 12:07:30,411 attempt 1/10 2016-08-09 12:07:30,411 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/613fec9a571e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 12:07:30,716 unpacking tar archive at: fx-team-613fec9a571e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.679475 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 12:07:30.616762) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 12:07:30.617113) ========= script_repo_revision: 613fec9a571e48c64918ebd0a8153096300ff992 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 12:07:30.617565) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 12:07:30.617863) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 12:07:30.628730) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-08-09 12:07:30.629127) ========= /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=9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:07:31 INFO - MultiFileLogger online at 20160809 12:07:31 in /builds/slave/test 12:07:31 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 12:07:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:07:31 INFO - {'append_to_log': False, 12:07:31 INFO - 'base_work_dir': '/builds/slave/test', 12:07:31 INFO - 'blob_upload_branch': 'Fx-Team', 12:07:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:07:31 INFO - 'branch': 'Fx-Team', 12:07:31 INFO - 'buildbot_json_path': 'buildprops.json', 12:07:31 INFO - 'config_files': ('talos/linux_config.py',), 12:07:31 INFO - 'default_actions': ('clobber', 12:07:31 INFO - 'read-buildbot-config', 12:07:31 INFO - 'download-and-extract', 12:07:31 INFO - 'populate-webroot', 12:07:31 INFO - 'create-virtualenv', 12:07:31 INFO - 'install', 12:07:31 INFO - 'run-tests'), 12:07:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:07:31 INFO - 'download_minidump_stackwalk': True, 12:07:31 INFO - 'download_symbols': 'ondemand', 12:07:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:07:31 INFO - 'tooltool.py': '/tools/tooltool.py', 12:07:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:07:31 INFO - '/tools/misc-python/virtualenv.py')}, 12:07:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:07:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:07:31 INFO - 'installer_path': 'installer.exe', 12:07:31 INFO - 'log_level': 'info', 12:07:31 INFO - 'log_name': 'talos', 12:07:31 INFO - 'log_to_console': True, 12:07:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:07:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:07:31 INFO - 'opt_config_files': (), 12:07:31 INFO - 'pip_index': False, 12:07:31 INFO - 'sps_profile': False, 12:07:31 INFO - 'sps_profile_interval': 0, 12:07:31 INFO - 'suite': 'dromaeojs', 12:07:31 INFO - 'system_bits': '32', 12:07:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:07:31 INFO - 'title': 'talos-linux64-ix-013', 12:07:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:07:31 INFO - 'use_talos_json': True, 12:07:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:07:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:07:31 INFO - 'work_dir': 'build'} 12:07:31 INFO - [mozharness: 2016-08-09 19:07:31.206378Z] Running clobber step. 12:07:31 INFO - Running pre-action listener: _resource_record_pre_action 12:07:31 INFO - Running main action method: clobber 12:07:31 INFO - rmtree: /builds/slave/test/build 12:07:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:07:31 INFO - Running post-action listener: _resource_record_post_action 12:07:31 INFO - [mozharness: 2016-08-09 19:07:31.664623Z] Finished clobber step (success) 12:07:31 INFO - [mozharness: 2016-08-09 19:07:31.664723Z] Running read-buildbot-config step. 12:07:31 INFO - Running pre-action listener: _resource_record_pre_action 12:07:31 INFO - Running main action method: read_buildbot_config 12:07:31 INFO - Using buildbot properties: 12:07:31 INFO - { 12:07:31 INFO - "project": "", 12:07:31 INFO - "product": "firefox", 12:07:31 INFO - "script_repo_revision": "production", 12:07:31 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 12:07:31 INFO - "repository": "", 12:07:31 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 12:07:31 INFO - "buildid": "20160809083002", 12:07:31 INFO - "pgo_build": "True", 12:07:31 INFO - "basedir": "/builds/slave/test", 12:07:31 INFO - "buildnumber": 1446, 12:07:31 INFO - "slavename": "talos-linux64-ix-013", 12:07:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:07:31 INFO - "platform": "ubuntu64_hw", 12:07:31 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 12:07:31 INFO - "branch": "fx-team", 12:07:31 INFO - "repo_path": "integration/fx-team", 12:07:31 INFO - "revision": "613fec9a571e48c64918ebd0a8153096300ff992", 12:07:31 INFO - "stage_platform": "linux64-pgo", 12:07:31 INFO - "builduid": "31d038f94ce24eaf9cd0f58e7c63cfd8", 12:07:31 INFO - "slavebuilddir": "test" 12:07:31 INFO - } 12:07:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 12:07:31 INFO - Running post-action listener: _resource_record_post_action 12:07:31 INFO - [mozharness: 2016-08-09 19:07:31.667160Z] Finished read-buildbot-config step (success) 12:07:31 INFO - [mozharness: 2016-08-09 19:07:31.667249Z] Running download-and-extract step. 12:07:31 INFO - Running pre-action listener: _resource_record_pre_action 12:07:31 INFO - Running main action method: download_and_extract 12:07:31 INFO - mkdir: /builds/slave/test/build/tests 12:07: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')]} 12:07:31 INFO - trying https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:07:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/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 12:07:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/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 12:07:32 INFO - Downloaded 1361 bytes. 12:07:32 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:07:32 INFO - Using the following test package requirements: 12:07:32 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 12:07:32 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:32 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:07:32 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:32 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:07:32 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:32 INFO - u'jsshell-linux-x86_64.zip'], 12:07:32 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:32 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:07:32 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 12:07:32 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:32 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:07:32 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:32 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:07:32 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:32 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:07:32 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:07:32 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:07:32 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:07:32 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')]} 12:07:32 INFO - trying https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:07:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/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 12:07:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/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 12:07:33 INFO - Downloaded 21830145 bytes. 12:07:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:07:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:07:34 INFO - Return code: 0 12:07:34 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 12:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:34 INFO - trying https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:07:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/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 12:07:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/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 12:07:35 INFO - Downloaded 21830145 bytes. 12:07:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:07:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:07:36 INFO - Return code: 0 12:07: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')]} 12:07:36 INFO - trying https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 12:07:36 INFO - Downloading https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/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 12:07:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/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 12:07:37 INFO - Downloaded 14413723 bytes. 12:07:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:07:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 12:07:38 INFO - Return code: 0 12:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:38 INFO - trying https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:07:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/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 12:07:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/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 12:07:40 INFO - Downloaded 59330161 bytes. 12:07:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:07:40 INFO - mkdir: /builds/slave/test/properties 12:07:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:07:40 INFO - Writing to file /builds/slave/test/properties/build_url 12:07:40 INFO - Contents: 12:07:40 INFO - build_url:https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:07:41 INFO - Running post-action listener: _resource_record_post_action 12:07:41 INFO - Running post-action listener: set_extra_try_arguments 12:07:41 INFO - [mozharness: 2016-08-09 19:07:41.065161Z] Finished download-and-extract step (success) 12:07:41 INFO - [mozharness: 2016-08-09 19:07:41.065395Z] Running populate-webroot step. 12:07:41 INFO - Running pre-action listener: _resource_record_pre_action 12:07:41 INFO - Running main action method: populate_webroot 12:07:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:07:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:07:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:07:41 INFO - '--tppagecycles', 12:07:41 INFO - '7'], 12:07:41 INFO - 'tests': ['tsvgm']}}, 12:07:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:07:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:07:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:07:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:07:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:07:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:07:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:41 INFO - 'talos_options': ['--disable-e10s'], 12:07:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:07:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:07:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:41 INFO - 'talos_options': ['--disable-e10s'], 12:07:41 INFO - 'tests': ['damp', 'tps']}, 12:07:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:41 INFO - 'tests': ['damp', 'tps']}, 12:07:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:07:41 INFO - 'tests': ['dromaeo_dom']}, 12:07:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:07:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:07:41 INFO - 'tests': ['basic_compositor_video']}, 12:07:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:07:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:07:41 INFO - 'tests': ['a11yr', 12:07:41 INFO - 'ts_paint', 12:07:41 INFO - 'tpaint', 12:07:41 INFO - 'sessionrestore', 12:07:41 INFO - 'sessionrestore_no_auto_restore', 12:07:41 INFO - 'tabpaint']}, 12:07:41 INFO - 'other-e10s': {'tests': ['a11yr', 12:07:41 INFO - 'ts_paint', 12:07:41 INFO - 'tpaint', 12:07:41 INFO - 'sessionrestore', 12:07:41 INFO - 'sessionrestore_no_auto_restore', 12:07:41 INFO - 'tabpaint']}, 12:07:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:07:41 INFO - 'ts_paint', 12:07:41 INFO - 'tpaint', 12:07:41 INFO - 'sessionrestore', 12:07:41 INFO - 'sessionrestore_no_auto_restore', 12:07:41 INFO - 'tabpaint']}, 12:07:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:07:41 INFO - 'ts_paint', 12:07:41 INFO - 'tpaint', 12:07:41 INFO - 'sessionrestore', 12:07:41 INFO - 'sessionrestore_no_auto_restore', 12:07:41 INFO - 'tabpaint']}, 12:07:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:07:41 INFO - 'tests': ['a11yr', 12:07:41 INFO - 'ts_paint', 12:07:41 INFO - 'tpaint', 12:07:41 INFO - 'sessionrestore', 12:07:41 INFO - 'sessionrestore_no_auto_restore', 12:07:41 INFO - 'tabpaint']}, 12:07:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:07:41 INFO - 'tests': ['a11yr', 12:07:41 INFO - 'ts_paint', 12:07:41 INFO - 'tpaint', 12:07:41 INFO - 'sessionrestore', 12:07:41 INFO - 'sessionrestore_no_auto_restore', 12:07:41 INFO - 'tabpaint']}, 12:07:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:07:41 INFO - 'tests': ['tsvgx', 12:07:41 INFO - 'tsvgr_opacity', 12:07:41 INFO - 'tart', 12:07:41 INFO - 'tscrollx', 12:07:41 INFO - 'cart']}, 12:07:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:07:41 INFO - 'tsvgr_opacity', 12:07:41 INFO - 'tart', 12:07:41 INFO - 'tscrollx', 12:07:41 INFO - 'cart']}, 12:07:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:41 INFO - 'talos_options': ['--disable-e10s'], 12:07:41 INFO - 'tests': ['tp5o']}, 12:07:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:41 INFO - 'tests': ['tp5o']}, 12:07:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:41 INFO - 'talos_options': ['--disable-e10s', 12:07:41 INFO - '--xperf_path', 12:07:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:07:41 INFO - 'tests': ['tp5n']}, 12:07:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:41 INFO - 'talos_options': ['--xperf_path', 12:07:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:07:41 INFO - 'tests': ['tp5n']}}, 12:07:41 INFO - 'talos.zip': {'path': '', 12:07:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:07:41 INFO - Running post-action listener: _resource_record_post_action 12:07:41 INFO - [mozharness: 2016-08-09 19:07:41.081337Z] Finished populate-webroot step (success) 12:07:41 INFO - [mozharness: 2016-08-09 19:07:41.081451Z] Running create-virtualenv step. 12:07:41 INFO - Running pre-action listener: _resource_record_pre_action 12:07:41 INFO - Running main action method: create_virtualenv 12:07:41 INFO - Creating virtualenv /builds/slave/test/build/venv 12:07:41 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 12:07:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:07:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:07:41 INFO - Using real prefix '/usr' 12:07:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:07:41 INFO - Installing distribute.............................................................................................................................................................................................done. 12:07:43 INFO - Installing pip.................done. 12:07:43 INFO - Return code: 0 12:07:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:43 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 0x1ecb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218a3b0>, '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 0x218b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218b740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', '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 12:07:43 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 12:07:43 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 12:07:43 INFO - Using env: {'DISPLAY': ':0', 12:07:43 INFO - 'HOME': '/home/cltbld', 12:07:43 INFO - 'LANG': 'en_US.UTF-8', 12:07:43 INFO - 'LANGUAGE': 'en_US:en', 12:07:43 INFO - 'LOGNAME': 'cltbld', 12:07:43 INFO - 'MAIL': '/var/mail/cltbld', 12:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:43 INFO - 'MOZ_NO_REMOTE': '1', 12:07:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:43 INFO - 'NO_EM_RESTART': '1', 12:07:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:43 INFO - 'PWD': '/builds/slave/test', 12:07:43 INFO - 'SHELL': '/bin/bash', 12:07:43 INFO - 'SHLVL': '1', 12:07:43 INFO - 'TERM': 'linux', 12:07:43 INFO - 'TMOUT': '86400', 12:07:43 INFO - 'USER': 'cltbld', 12:07:43 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', 12:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:43 INFO - '_': '/tools/buildbot/bin/python'} 12:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:43 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 12:07:43 INFO - Cleaning up... 12:07:43 INFO - Return code: 0 12:07:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:43 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 0x1ecb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218a3b0>, '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 0x218b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218b740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', '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 12:07:43 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 12:07:43 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 12:07:43 INFO - Using env: {'DISPLAY': ':0', 12:07:43 INFO - 'HOME': '/home/cltbld', 12:07:43 INFO - 'LANG': 'en_US.UTF-8', 12:07:43 INFO - 'LANGUAGE': 'en_US:en', 12:07:43 INFO - 'LOGNAME': 'cltbld', 12:07:43 INFO - 'MAIL': '/var/mail/cltbld', 12:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:43 INFO - 'MOZ_NO_REMOTE': '1', 12:07:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:43 INFO - 'NO_EM_RESTART': '1', 12:07:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:43 INFO - 'PWD': '/builds/slave/test', 12:07:43 INFO - 'SHELL': '/bin/bash', 12:07:43 INFO - 'SHLVL': '1', 12:07:43 INFO - 'TERM': 'linux', 12:07:43 INFO - 'TMOUT': '86400', 12:07:43 INFO - 'USER': 'cltbld', 12:07:43 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', 12:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:43 INFO - '_': '/tools/buildbot/bin/python'} 12:07:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:44 INFO - Downloading/unpacking psutil>=3.1.1 12:07:44 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 12:07:44 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 12:07:44 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 12:07:44 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 12:07:44 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 12:07:44 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 12:07:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:07:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:07:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:07:46 INFO - Installing collected packages: psutil 12:07:46 INFO - Running setup.py install for psutil 12:07:46 INFO - building 'psutil._psutil_linux' extension 12:07:46 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 12:07:46 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 12:07:46 INFO - building 'psutil._psutil_posix' extension 12:07:46 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 12:07:46 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 12:07:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:07:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:07:46 INFO - Successfully installed psutil 12:07:46 INFO - Cleaning up... 12:07:46 INFO - Return code: 0 12:07:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:07:46 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')]} 12:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:46 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')]} 12:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:46 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 0x1ecb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218a3b0>, '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 0x218b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218b740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', '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 12:07:46 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 12:07:46 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 12:07:46 INFO - Using env: {'DISPLAY': ':0', 12:07:46 INFO - 'HOME': '/home/cltbld', 12:07:46 INFO - 'LANG': 'en_US.UTF-8', 12:07:46 INFO - 'LANGUAGE': 'en_US:en', 12:07:46 INFO - 'LOGNAME': 'cltbld', 12:07:46 INFO - 'MAIL': '/var/mail/cltbld', 12:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:46 INFO - 'MOZ_NO_REMOTE': '1', 12:07:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:46 INFO - 'NO_EM_RESTART': '1', 12:07:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:46 INFO - 'PWD': '/builds/slave/test', 12:07:46 INFO - 'SHELL': '/bin/bash', 12:07:46 INFO - 'SHLVL': '1', 12:07:46 INFO - 'TERM': 'linux', 12:07:46 INFO - 'TMOUT': '86400', 12:07:46 INFO - 'USER': 'cltbld', 12:07:46 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', 12:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:46 INFO - '_': '/tools/buildbot/bin/python'} 12:07:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:07:46 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 12:07:46 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 12:07:46 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 12:07:46 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 12:07:46 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 12:07:46 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 12:07:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:07:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:07:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:07:48 INFO - Installing collected packages: mozsystemmonitor 12:07:48 INFO - Running setup.py install for mozsystemmonitor 12:07:48 INFO - Successfully installed mozsystemmonitor 12:07:48 INFO - Cleaning up... 12:07:48 INFO - Return code: 0 12:07:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:07:48 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')]} 12:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:48 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')]} 12:07:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:48 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 0x1ecb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218a3b0>, '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 0x218b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218b740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', '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 12:07:48 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 12:07:48 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 12:07:48 INFO - Using env: {'DISPLAY': ':0', 12:07:48 INFO - 'HOME': '/home/cltbld', 12:07:48 INFO - 'LANG': 'en_US.UTF-8', 12:07:48 INFO - 'LANGUAGE': 'en_US:en', 12:07:48 INFO - 'LOGNAME': 'cltbld', 12:07:48 INFO - 'MAIL': '/var/mail/cltbld', 12:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:48 INFO - 'MOZ_NO_REMOTE': '1', 12:07:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:48 INFO - 'NO_EM_RESTART': '1', 12:07:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:48 INFO - 'PWD': '/builds/slave/test', 12:07:48 INFO - 'SHELL': '/bin/bash', 12:07:48 INFO - 'SHLVL': '1', 12:07:48 INFO - 'TERM': 'linux', 12:07:48 INFO - 'TMOUT': '86400', 12:07:48 INFO - 'USER': 'cltbld', 12:07:48 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', 12:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:48 INFO - '_': '/tools/buildbot/bin/python'} 12:07:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:49 INFO - Downloading/unpacking jsonschema==2.5.1 12:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:07:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:07:51 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 12:07:51 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 12:07:51 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 12:07:51 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 12:07:51 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 12:07:51 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 12:07:51 INFO - Downloading functools32-3.2.3-2.tar.gz 12:07:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:07:51 INFO - warning: no files found matching '*.txt' 12:07:51 INFO - no previously-included directories found matching 'build' 12:07:51 INFO - no previously-included directories found matching 'dist' 12:07:51 INFO - no previously-included directories found matching '.git*' 12:07:51 INFO - Installing collected packages: jsonschema, functools32 12:07:51 INFO - Running setup.py install for functools32 12:07:51 INFO - warning: no files found matching '*.txt' 12:07:51 INFO - no previously-included directories found matching 'build' 12:07:51 INFO - no previously-included directories found matching 'dist' 12:07:51 INFO - no previously-included directories found matching '.git*' 12:07:51 INFO - Successfully installed jsonschema functools32 12:07:51 INFO - Cleaning up... 12:07:51 INFO - Return code: 0 12:07:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:07:51 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')]} 12:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:51 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')]} 12:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:51 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 0x1ecb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218a3b0>, '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 0x218b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218b740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', '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 12:07:51 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 12:07:51 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 12:07:51 INFO - Using env: {'DISPLAY': ':0', 12:07:51 INFO - 'HOME': '/home/cltbld', 12:07:51 INFO - 'LANG': 'en_US.UTF-8', 12:07:51 INFO - 'LANGUAGE': 'en_US:en', 12:07:51 INFO - 'LOGNAME': 'cltbld', 12:07:51 INFO - 'MAIL': '/var/mail/cltbld', 12:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:51 INFO - 'MOZ_NO_REMOTE': '1', 12:07:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:51 INFO - 'NO_EM_RESTART': '1', 12:07:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:51 INFO - 'PWD': '/builds/slave/test', 12:07:51 INFO - 'SHELL': '/bin/bash', 12:07:51 INFO - 'SHLVL': '1', 12:07:51 INFO - 'TERM': 'linux', 12:07:51 INFO - 'TMOUT': '86400', 12:07:51 INFO - 'USER': 'cltbld', 12:07:51 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', 12:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:51 INFO - '_': '/tools/buildbot/bin/python'} 12:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:07:51 INFO - Cleaning up... 12:07:51 INFO - Return code: 0 12:07:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:07:51 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')]} 12:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:51 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')]} 12:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:51 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 0x1ecb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218a3b0>, '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 0x218b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218b740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', '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 12:07:51 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 12:07:51 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 12:07:51 INFO - Using env: {'DISPLAY': ':0', 12:07:51 INFO - 'HOME': '/home/cltbld', 12:07:51 INFO - 'LANG': 'en_US.UTF-8', 12:07:51 INFO - 'LANGUAGE': 'en_US:en', 12:07:51 INFO - 'LOGNAME': 'cltbld', 12:07:51 INFO - 'MAIL': '/var/mail/cltbld', 12:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:51 INFO - 'MOZ_NO_REMOTE': '1', 12:07:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:51 INFO - 'NO_EM_RESTART': '1', 12:07:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:51 INFO - 'PWD': '/builds/slave/test', 12:07:51 INFO - 'SHELL': '/bin/bash', 12:07:51 INFO - 'SHLVL': '1', 12:07:51 INFO - 'TERM': 'linux', 12:07:51 INFO - 'TMOUT': '86400', 12:07:51 INFO - 'USER': 'cltbld', 12:07:51 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', 12:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:51 INFO - '_': '/tools/buildbot/bin/python'} 12:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:51 INFO - Downloading/unpacking blobuploader==1.2.4 12:07:51 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 12:07:51 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 12:07:51 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 12:07:51 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 12:07:51 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 12:07:51 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 12:07:53 INFO - Downloading blobuploader-1.2.4.tar.gz 12:07:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:07:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:07:53 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 12:07:53 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 12:07:53 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 12:07:53 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 12:07:53 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 12:07:53 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 12:07:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:07:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07:54 INFO - Downloading docopt-0.6.1.tar.gz 12:07:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:07:54 INFO - Installing collected packages: blobuploader, requests, docopt 12:07:54 INFO - Running setup.py install for blobuploader 12:07:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:07:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:07:54 INFO - Running setup.py install for requests 12:07:55 INFO - Running setup.py install for docopt 12:07:55 INFO - Successfully installed blobuploader requests docopt 12:07:55 INFO - Cleaning up... 12:07:55 INFO - Return code: 0 12:07:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07: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')]} 12:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07: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')]} 12:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:55 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 0x1ecb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218a3b0>, '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 0x218b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218b740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', '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 12:07:55 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 12:07:55 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 12:07:55 INFO - Using env: {'DISPLAY': ':0', 12:07:55 INFO - 'HOME': '/home/cltbld', 12:07:55 INFO - 'LANG': 'en_US.UTF-8', 12:07:55 INFO - 'LANGUAGE': 'en_US:en', 12:07:55 INFO - 'LOGNAME': 'cltbld', 12:07:55 INFO - 'MAIL': '/var/mail/cltbld', 12:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:55 INFO - 'MOZ_NO_REMOTE': '1', 12:07:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:55 INFO - 'NO_EM_RESTART': '1', 12:07:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:55 INFO - 'PWD': '/builds/slave/test', 12:07:55 INFO - 'SHELL': '/bin/bash', 12:07:55 INFO - 'SHLVL': '1', 12:07:55 INFO - 'TERM': 'linux', 12:07:55 INFO - 'TMOUT': '86400', 12:07:55 INFO - 'USER': 'cltbld', 12:07:55 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', 12:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:55 INFO - '_': '/tools/buildbot/bin/python'} 12:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:07:55 INFO - Running setup.py (path:/tmp/pip-BHJ1Ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:07:55 INFO - Running setup.py (path:/tmp/pip-a604ZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:07:55 INFO - Running setup.py (path:/tmp/pip-p6KmRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:07:55 INFO - Running setup.py (path:/tmp/pip-ryNc5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:07:55 INFO - Running setup.py (path:/tmp/pip-F03uUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:07:55 INFO - Running setup.py (path:/tmp/pip-y9HjXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:07:55 INFO - Running setup.py (path:/tmp/pip-ggxG7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:07:55 INFO - Running setup.py (path:/tmp/pip-g5oFqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:07:55 INFO - Running setup.py (path:/tmp/pip-am8_UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:07:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:07:56 INFO - Running setup.py (path:/tmp/pip-F6Fxx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:07:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:07:56 INFO - Running setup.py (path:/tmp/pip-ZHBq5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:07:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:07:56 INFO - Running setup.py (path:/tmp/pip-bDGo6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:07:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:07:56 INFO - Running setup.py (path:/tmp/pip-t_zjwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:07:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:07:56 INFO - Running setup.py (path:/tmp/pip-QDw0Jd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:07:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:56 INFO - Running setup.py (path:/tmp/pip-k2NFcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:07:56 INFO - Running setup.py (path:/tmp/pip-ycLRQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:07:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:07:56 INFO - Running setup.py (path:/tmp/pip-M9dE73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:07:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:07:56 INFO - Running setup.py install for manifestparser 12:07:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:07:56 INFO - Running setup.py install for mozcrash 12:07:56 INFO - Running setup.py install for mozdebug 12:07:56 INFO - Running setup.py install for mozdevice 12:07:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:07:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:07:57 INFO - Running setup.py install for mozfile 12:07:57 INFO - Running setup.py install for mozhttpd 12:07:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:07:57 INFO - Running setup.py install for mozinfo 12:07:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:07:57 INFO - Running setup.py install for mozInstall 12:07:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:07:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:07:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:07:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:07:57 INFO - Running setup.py install for mozleak 12:07:57 INFO - Running setup.py install for mozlog 12:07:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:07:57 INFO - Running setup.py install for moznetwork 12:07:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:07:57 INFO - Running setup.py install for mozprocess 12:07:57 INFO - Running setup.py install for mozprofile 12:07:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:07:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:07:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:07:57 INFO - Running setup.py install for mozrunner 12:07:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:07:58 INFO - Running setup.py install for mozscreenshot 12:07:58 INFO - Running setup.py install for moztest 12:07:58 INFO - Running setup.py install for mozversion 12:07:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:07:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:07:58 INFO - Cleaning up... 12:07:58 INFO - Return code: 0 12:07:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07: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')]} 12:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07: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')]} 12:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:58 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 0x1ecb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218a3b0>, '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 0x218b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218b740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', '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 12:07:58 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 12:07:58 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 12:07:58 INFO - Using env: {'DISPLAY': ':0', 12:07:58 INFO - 'HOME': '/home/cltbld', 12:07:58 INFO - 'LANG': 'en_US.UTF-8', 12:07:58 INFO - 'LANGUAGE': 'en_US:en', 12:07:58 INFO - 'LOGNAME': 'cltbld', 12:07:58 INFO - 'MAIL': '/var/mail/cltbld', 12:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:58 INFO - 'MOZ_NO_REMOTE': '1', 12:07:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:58 INFO - 'NO_EM_RESTART': '1', 12:07:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:58 INFO - 'PWD': '/builds/slave/test', 12:07:58 INFO - 'SHELL': '/bin/bash', 12:07:58 INFO - 'SHLVL': '1', 12:07:58 INFO - 'TERM': 'linux', 12:07:58 INFO - 'TMOUT': '86400', 12:07:58 INFO - 'USER': 'cltbld', 12:07:58 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', 12:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:58 INFO - '_': '/tools/buildbot/bin/python'} 12:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:07:58 INFO - Running setup.py (path:/tmp/pip-BWmmRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:07:58 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)) 12:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:07:58 INFO - Running setup.py (path:/tmp/pip-y8hC8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:07:58 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)) 12:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:07:58 INFO - Running setup.py (path:/tmp/pip-YR_qxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:07:58 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)) 12:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:07:58 INFO - Running setup.py (path:/tmp/pip-N0ByaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:07:58 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)) 12:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:07:58 INFO - Running setup.py (path:/tmp/pip-ZlRkD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:07:59 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)) 12:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:07:59 INFO - Running setup.py (path:/tmp/pip-qW9pSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:07:59 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)) 12:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:07:59 INFO - Running setup.py (path:/tmp/pip-yXTVMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:07:59 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)) 12:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:07:59 INFO - Running setup.py (path:/tmp/pip-7E7JZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:07:59 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)) 12:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:07:59 INFO - Running setup.py (path:/tmp/pip-CltNKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:07:59 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)) 12:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:07:59 INFO - Running setup.py (path:/tmp/pip-Z3ck6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:07:59 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)) 12:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:07:59 INFO - Running setup.py (path:/tmp/pip-X7YZz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:07:59 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)) 12:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:07:59 INFO - Running setup.py (path:/tmp/pip-YbfbLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:07:59 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)) 12:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:07:59 INFO - Running setup.py (path:/tmp/pip-pln9Te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:07:59 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)) 12:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:07:59 INFO - Running setup.py (path:/tmp/pip-DpfFKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:07:59 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)) 12:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:59 INFO - Running setup.py (path:/tmp/pip-DDd5Kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:59 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)) 12:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:07:59 INFO - Running setup.py (path:/tmp/pip-mjbrhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:07:59 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)) 12:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:07:59 INFO - Running setup.py (path:/tmp/pip-CByMxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:07:59 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)) 12:07:59 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)) 12:07:59 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)) 12:07:59 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)) 12:07:59 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)) 12:07:59 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)) 12:07:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:01 INFO - Downloading blessings-1.6.tar.gz 12:08:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:08:01 INFO - Installing collected packages: blessings 12:08:01 INFO - Running setup.py install for blessings 12:08:01 INFO - Successfully installed blessings 12:08:01 INFO - Cleaning up... 12:08:02 INFO - Return code: 0 12:08:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:08:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:08:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:08:02 INFO - Reading from file tmpfile_stdout 12:08:02 INFO - Current package versions: 12:08:02 INFO - argparse == 1.2.1 12:08:02 INFO - blessings == 1.6 12:08:02 INFO - blobuploader == 1.2.4 12:08:02 INFO - docopt == 0.6.1 12:08:02 INFO - functools32 == 3.2.3-2 12:08:02 INFO - jsonschema == 2.5.1 12:08:02 INFO - manifestparser == 1.1 12:08:02 INFO - mozInstall == 1.12 12:08:02 INFO - mozcrash == 0.17 12:08:02 INFO - mozdebug == 0.1 12:08:02 INFO - mozdevice == 0.48 12:08:02 INFO - mozfile == 1.2 12:08:02 INFO - mozhttpd == 0.7 12:08:02 INFO - mozinfo == 0.9 12:08:02 INFO - mozleak == 0.1 12:08:02 INFO - mozlog == 3.3 12:08:02 INFO - moznetwork == 0.27 12:08:02 INFO - mozprocess == 0.23 12:08:02 INFO - mozprofile == 0.28 12:08:02 INFO - mozrunner == 6.12 12:08:02 INFO - mozscreenshot == 0.1 12:08:02 INFO - mozsystemmonitor == 0.3 12:08:02 INFO - moztest == 0.7 12:08:02 INFO - mozversion == 1.4 12:08:02 INFO - psutil == 3.1.1 12:08:02 INFO - requests == 1.2.3 12:08:02 INFO - wsgiref == 0.1.2 12:08:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08: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')]} 12:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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')]} 12:08:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:02 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 0x1ecb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218a3b0>, '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 0x218b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218b740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', '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 12:08:02 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 12:08:02 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 12:08:02 INFO - Using env: {'DISPLAY': ':0', 12:08:02 INFO - 'HOME': '/home/cltbld', 12:08:02 INFO - 'LANG': 'en_US.UTF-8', 12:08:02 INFO - 'LANGUAGE': 'en_US:en', 12:08:02 INFO - 'LOGNAME': 'cltbld', 12:08:02 INFO - 'MAIL': '/var/mail/cltbld', 12:08:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:02 INFO - 'MOZ_NO_REMOTE': '1', 12:08:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:02 INFO - 'NO_EM_RESTART': '1', 12:08:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:02 INFO - 'PWD': '/builds/slave/test', 12:08:02 INFO - 'SHELL': '/bin/bash', 12:08:02 INFO - 'SHLVL': '1', 12:08:02 INFO - 'TERM': 'linux', 12:08:02 INFO - 'TMOUT': '86400', 12:08:02 INFO - 'USER': 'cltbld', 12:08:02 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', 12:08:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:02 INFO - '_': '/tools/buildbot/bin/python'} 12:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:02 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)) 12:08:02 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)) 12:08:02 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)) 12:08:02 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)) 12:08:02 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)) 12:08:02 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)) 12:08:02 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)) 12:08:02 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)) 12:08:02 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)) 12:08:02 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)) 12:08:02 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)) 12:08:02 INFO - Cleaning up... 12:08:02 INFO - Return code: 0 12:08:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:08: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')]} 12:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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')]} 12:08:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:02 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 0x1ecb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218a3b0>, '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 0x218b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218b740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', '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 12:08:02 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 12:08:02 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 12:08:02 INFO - Using env: {'DISPLAY': ':0', 12:08:02 INFO - 'HOME': '/home/cltbld', 12:08:02 INFO - 'LANG': 'en_US.UTF-8', 12:08:02 INFO - 'LANGUAGE': 'en_US:en', 12:08:02 INFO - 'LOGNAME': 'cltbld', 12:08:02 INFO - 'MAIL': '/var/mail/cltbld', 12:08:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:02 INFO - 'MOZ_NO_REMOTE': '1', 12:08:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:02 INFO - 'NO_EM_RESTART': '1', 12:08:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:02 INFO - 'PWD': '/builds/slave/test', 12:08:02 INFO - 'SHELL': '/bin/bash', 12:08:02 INFO - 'SHLVL': '1', 12:08:02 INFO - 'TERM': 'linux', 12:08:02 INFO - 'TMOUT': '86400', 12:08:02 INFO - 'USER': 'cltbld', 12:08:02 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', 12:08:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:02 INFO - '_': '/tools/buildbot/bin/python'} 12:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:08:02 INFO - Cleaning up... 12:08:02 INFO - Return code: 0 12:08:02 INFO - Running post-action listener: _resource_record_post_action 12:08:02 INFO - Running post-action listener: _start_resource_monitoring 12:08:02 INFO - Starting resource monitoring. 12:08:02 INFO - [mozharness: 2016-08-09 19:08:02.696265Z] Finished create-virtualenv step (success) 12:08:02 INFO - [mozharness: 2016-08-09 19:08:02.696700Z] Running install step. 12:08:02 INFO - Running pre-action listener: _resource_record_pre_action 12:08:02 INFO - Running main action method: install 12:08:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:08:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:08:02 INFO - Reading from file tmpfile_stdout 12:08:02 INFO - Detecting whether we're running mozinstall >=1.0... 12:08:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:08:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:08:02 INFO - Reading from file tmpfile_stdout 12:08:02 INFO - Output received: 12:08:02 INFO - Usage: mozinstall [options] installer 12:08:02 INFO - Options: 12:08:02 INFO - -h, --help show this help message and exit 12:08:02 INFO - -d DEST, --destination=DEST 12:08:02 INFO - Directory to install application into. [default: 12:08:02 INFO - "/builds/slave/test"] 12:08:02 INFO - --app=APP Application being installed. [default: firefox] 12:08:02 INFO - mkdir: /builds/slave/test/build/application 12:08:02 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'] 12:08:02 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 12:08:19 INFO - Reading from file tmpfile_stdout 12:08:19 INFO - Output received: 12:08:19 INFO - /builds/slave/test/build/application/firefox/firefox 12:08:19 INFO - Running post-action listener: _resource_record_post_action 12:08:19 INFO - [mozharness: 2016-08-09 19:08:19.866312Z] Finished install step (success) 12:08:19 INFO - [mozharness: 2016-08-09 19:08:19.866563Z] Running run-tests step. 12:08:19 INFO - Running pre-action listener: _resource_record_pre_action 12:08:19 INFO - Running main action method: run_tests 12:08:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:08:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:08:19 INFO - Python 2.7.3 12:08:19 INFO - Return code: 0 12:08:19 INFO - grabbing minidump binary from tooltool 12:08:19 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')]} 12:08:19 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 0x218a3b0>, '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 0x218b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:08:19 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 12:08:19 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 12:08:19 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 12:08:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:08:19 INFO - Return code: 0 12:08:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:08:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:08:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:08:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:08:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:08:19 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/Y3hWTioAQ9mvtBNdRdmuKA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 12:08:19 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/Y3hWTioAQ9mvtBNdRdmuKA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --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 12:08:19 INFO - Using env: {'DISPLAY': ':0', 12:08:19 INFO - 'HOME': '/home/cltbld', 12:08:19 INFO - 'LANG': 'en_US.UTF-8', 12:08:19 INFO - 'LANGUAGE': 'en_US:en', 12:08:19 INFO - 'LOGNAME': 'cltbld', 12:08:19 INFO - 'MAIL': '/var/mail/cltbld', 12:08:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:08:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:19 INFO - 'MOZ_NO_REMOTE': '1', 12:08:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:08:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:19 INFO - 'NO_EM_RESTART': '1', 12:08:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:19 INFO - 'PWD': '/builds/slave/test', 12:08:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:08:19 INFO - 'SHELL': '/bin/bash', 12:08:19 INFO - 'SHLVL': '1', 12:08:19 INFO - 'TERM': 'linux', 12:08:19 INFO - 'TMOUT': '86400', 12:08:19 INFO - 'USER': 'cltbld', 12:08:19 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676', 12:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:19 INFO - '_': '/tools/buildbot/bin/python'} 12:08:19 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/Y3hWTioAQ9mvtBNdRdmuKA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 12:08:20 INFO - mozversion application_buildid: 20160809083002 12:08:20 INFO - mozversion application_changeset: 613fec9a571e48c64918ebd0a8153096300ff992 12:08:20 INFO - mozversion application_display_name: Nightly 12:08:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:08:20 INFO - mozversion application_name: Firefox 12:08:20 INFO - mozversion application_remotingname: firefox 12:08:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:08:20 INFO - mozversion application_vendor: Mozilla 12:08:20 INFO - mozversion application_version: 51.0a1 12:08:20 INFO - mozversion platform_buildid: 20160809083002 12:08:20 INFO - mozversion platform_changeset: 613fec9a571e48c64918ebd0a8153096300ff992 12:08:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:08:20 INFO - mozversion platform_version: 51.0a1 12:08:20 INFO - using testdate: 1470769700 12:08:20 INFO - actual date: 1470769700 12:08:20 INFO - starting webserver on 'localhost:43692' 12:08:20 INFO - SUITE-START | Running 2 tests 12:08:20 INFO - TEST-START | dromaeo_css 12:08:20 INFO - operating with platform_type : linux_ 12:08:20 INFO - Initialising browser for dromaeo_css test... 12:08:20 INFO - TEST-INFO | started process 28698 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6dW6_W/profile http://localhost:43692/getInfo.html) 12:08:24 INFO - PROCESS | 28698 | __metrics Screen width/height:1600/1200 12:08:24 INFO - PROCESS | 28698 | colorDepth:24 12:08:24 INFO - PROCESS | 28698 | Browser inner width/height: 1024/697 12:08:24 INFO - PROCESS | 28698 | __metrics 12:08:25 INFO - PROCESS | 28698 | 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" 12:08:25 INFO - PROCESS | 28698 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:08:25 INFO - PROCESS | 28698 | console.error: PushService: 12:08:25 INFO - PROCESS | 28698 | stateChangeProcessEnqueue: Error transitioning state 12:08:25 INFO - PROCESS | 28698 | UnknownError 12:08:26 INFO - TEST-INFO | 28698: exit 0 12:08:26 INFO - Browser initialized. 12:08:26 INFO - Running cycle 1/1 for dromaeo_css test... 12:08:26 INFO - TEST-INFO | started process 28779 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6dW6_W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:08:37 INFO - PROCESS | 28779 | RSS: Main: 155729920 12:08:37 INFO - PROCESS | 28779 | 12:09:28 INFO - PROCESS | 28779 | 1470769768656 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] 12:11:39 INFO - PROCESS | 28779 | Cycle 1(1): loaded http://localhost:43692/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43692/tests/dromaeo/cssquery-ext.html) 12:11:40 INFO - PROCESS | 28779 | RSS: Main: 259682304 12:11:40 INFO - PROCESS | 28779 | 12:14:42 INFO - PROCESS | 28779 | Cycle 1(1): loaded http://localhost:43692/tests/dromaeo/cssquery-ext.html (next: http://localhost:43692/tests/dromaeo/cssquery-jquery.html) 12:14:43 INFO - PROCESS | 28779 | RSS: Main: 242360320 12:14:43 INFO - PROCESS | 28779 | 12:17:45 INFO - PROCESS | 28779 | Cycle 1(1): loaded http://localhost:43692/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43692/tests/dromaeo/cssquery-mootools.html) 12:17:45 INFO - PROCESS | 28779 | RSS: Main: 183377920 12:17:45 INFO - PROCESS | 28779 | 12:20:47 INFO - PROCESS | 28779 | Cycle 1(1): loaded http://localhost:43692/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43692/tests/dromaeo/cssquery-prototype.html) 12:20:48 INFO - PROCESS | 28779 | RSS: Main: 218075136 12:20:48 INFO - PROCESS | 28779 | 12:23:51 INFO - PROCESS | 28779 | Cycle 1(1): loaded http://localhost:43692/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43692/tests/dromaeo/cssquery-yui.html) 12:23:51 INFO - PROCESS | 28779 | RSS: Main: 183160832 12:23:51 INFO - PROCESS | 28779 | 12:26:54 INFO - PROCESS | 28779 | Cycle 1(1): loaded http://localhost:43692/tests/dromaeo/cssquery-yui.html (next: http://localhost:43692/tests/dromaeo/cssquery-dojo.html) 12:26:54 INFO - PROCESS | 28779 | RSS: Main: 272707584 12:26:54 INFO - PROCESS | 28779 | 12:26:54 INFO - PROCESS | 28779 | __start_tp_report 12:26:54 INFO - PROCESS | 28779 | _x_x_mozilla_page_load 12:26:54 INFO - PROCESS | 28779 | _x_x_mozilla_page_load_details 12:26:54 INFO - PROCESS | 28779 | |i|pagename|runs| 12:26:54 INFO - PROCESS | 28779 | |0;dojo.html;3096.9380612387754;3098.148009297443;3125.0312403155017;3142.6700196479383;3147.7009684080017;5281.735913204344;5376.247325374441;5378.408375078743;5491.066518691822;5494.1758736189595;5395;5397.136458166694;5402.594805389602;5441.782328706847;5475.753591088401;4825.951740482594;4907.926381104268;4910.607151427877;4914.262860570925;5076.822311219108;4988.226824842137;5022.146235140017;5039.798408063673;5042.495750424947;5054.494550544953;4802.711837289774;4852.417709874802;4915.483874193194;4926.55660990509;4947.802087916479;4903.239997800347;4904.264360345941;4904.950950490485;4910.361652985125;4949.620469210825;4874.780634871404;4927.556519913225;4945.629077819142;4957.454679985199;4968.130577149021;5302.098643230622;5328.813491527778;5374.919376209321;5404.270423492818;5478.041342765;5757.309122905236;5796.565257605696;5813.447722466359;5815.302163740336;5828.417158284137;5853.09277062056;5874.618149820248;5876.030454974924;5879.323877754027;5889.02831032879;5234.42421333653;5351.956368508181;5408;5411.215373770781;5464.754086066137;6045.703648029152;6050.637314582524;6088.1476593276975;6091.02543593023;6098.573099883054;5760.884782304331;5763.567732420001;5765.942340576625;5797.101449275369;5811.651300921958;5582.106862901916;5684;5737.254156959652;5747.166660834239;5903.763849445974;5751.4536119068625;5802.361740208574;5826.271716035496;5839.386864379264;5842.5618078644275;5779.2775903012125;5818.272715910512;5826.70866456684;5830.650160990354;5842.2405087338375;4340.500842403101;4391.012022294958;4435.489918659331;4444.355568442623;4472.195004899084;3955.7824319662695;3992.780397078189;4005.939910901339;4015.759054456742;4072.490938632671;5536.778528858881;5543.584231182596;5545.140503222007;5592.217089607459;5602.495775380154;5702.030654788696;5888.5289176865745;5893.381194974551;5895.35151133375;5902.114682797529;5439.592030597721;5457.454254574511;5476.151196564539;5489.615726899079;5558.082916318843;5025.969676216367;5039.294498770175;5042;5045.520675535819;5080.847574572768;5816.651000939957;6032.698365081728;6090.421409966223;6098.32918378978;6100.328963813977;5782.45066718661;5809.360970293349;5861.589688721749;5903.793367232126;5957.7319020645045;6057.545684073624;6099.75600975956;6111.144439778345;6111.816645500642;6113.51091912637;5563.860903477445;5755.453231942959;5760.020796464695;5769.509591684754;5772.913406298825;5793.623414478046;5822.82531524055;5873.794417195378;5914.02418600926;5976.641401515923;5724.4561766632105;5747.655140691572;5748;5762.913556296575;5767.423257674199;5605.691686957257;5738.598298119092;5754.884902302014;5755.5683323750045;5762.337331206797;5569.637973531708;5620.213170156099;5650.57620678459;5666.971665141813;5726.599138060463;5780.2485676863425;5811.331696854916;5841.240638716939;5854.912176317273;5863;5419.864503387289;5642.097264437671;5650.802221922378;5670.149477578396;5670.659760414388 12:26:54 INFO - PROCESS | 28779 | |1;ext.html;585.7510558012843;602.46972689369;605.8394525450755;608.5010291560922;619.9783007594732;33051.83474082629;35530.644693553055;35540.99999999997;35554.46668299977;35991.820040899795;45158.54841451585;46301.53698463014;46358;49898.251526227046;49908.750456247755;8812.559372031405;8953.597088131017;8958.641654333851;8958.686445974392;8960.910390896073;8763.123687631218;8874.955625221864;8890.466572005667;8897.911020889773;8898;8899.822003559924;8933.240674542672;8933.731988040368;8936.061713520085;8944.23973962214;8847.071057538962;8894.199522042982;8896.688615898413;8900;8914.064023277528;8817.29461643067;8882.067382924764;8884.156005179562;8891.911080889173;8894.021657617657;42903.785481072395;43290.567094329286;43306.35040474396;43312.13375732468;43322.56677433248;3495.2310491691787;3574.0528759878653;3668.761530500509;3882.650561449483;4079.5920407958965;33009.174770630685;33448.49827252593;33612.83193584041;34208.82895585531;35015.649843501495;4132.772697501636;4149.33610622299;4159.313713237312;4357.564243575649;4371.366151907955;368196;370512.1474392611;371011;371013;372613;61535.38464615386;61737.38262617406;61742.69128654418;61785.38214617886;61791.6910415445;37506.62493375031;64580;64734;64767.676161619835;64818.35181648217;16132.919335403483;17637.559061023578;17644.558886027953;17647.117644118;17703.645927081645;12639.43122559487;12646.873531264753;12662;12669.049821263443;12676.936615316865;19522.467004316088;19538;19617.117229724696;19698.70451943222;19761.50596235077;7004.198320671772;7013.964930175316;7086.255943125898;7167.104111986002;7172.390346820474;923.2660035271975;1017.3488967061087;1035.5805898611075;1072.367303291062;1143.5711608146944;30631.01604278075;32275.35449291048;32328.83835580807;32400.35199296048;32971;1043.9798988956393;1112.2436743014828;1128.937908415045;1162.4826952006445;1186.976260474803;16230.431934882223;16889.831101689073;16992.06543640087;17014.57463563421;17044.914775426045;443491;448375;448496;448516;448625;58493;59014.70492647595;59038.70480647569;59061.70469147627;59247.99999999914;1216.3006271393983;1309.9083064185606;1325.5890673891124;1351.067763243359;1388.7916812478209;32626.184345391554;33553.32893342168;34611.13472163216;34740;35127;59888;60399.698001509714;60409.39590604038;60568.6971565157;60577;452482;459362;460537;461022;461140;29467.705322946495;30106.032504637365;30195.245118872204;31425.52861707122;47353;50581;50765.40858983305;51324.48675513197;52198;52703.73648131735;41269.793651031556;41722.58277417308;41972.37041444442;42492.78753606212;43753.56246437622;41862.37206441845;42056.158876822905;42626.57373426226;43433.78283108564;44319;42081.36877946895;42928.57071429246;43034.56965430431;43792;43993.34009989789;35429.64570354367;37150.58521196114;37551.43672844964;37648.811755941046;38217.23565528618;28401.171800794327;28617.427651446436;28690.56964145498;28748.425031498835;29099.7090029097 12:26:54 INFO - PROCESS | 28779 | |2;jquery.html;353.93983022886107;391.32301119064;394.9368101103823;398.934175860983;423.7669281894957;14203.360848761804;16899;19445.708314375297;19482.805171948276;19636.312729054487;18677.346292879753;19206.46348292137;19229.42311730647;19406.22375104994;19434;5306.92039619406;5701.828945131642;5882.617629854067;5882.794102206423;5884.4703976642095;5485.149801780711;5616.887662246752;5626.859328516779;5630.296212973379;5636.436356364372;5570.878097836358;5606.439356064381;5621.4659607337235;5633.352164501073;5723.799667011655;5788.710564471759;5828.242328497311;5866.5306775458075;5872.119182122673;5873.383294754075;5576.9163462548095;5679.829605111853;5686.516646085088;5687.658740475585;5705.515031222351;12433.502659893593;12643.241405515699;12700.42848071839;12725;12741.490340386374;2266.5806825737127;2372.9644055339013;2520.3195137312823;2783.401568662747;2902.956455653167;9223.400478968913;9237.30720195988;9308.394954328014;9350.67272645461;9694.51527423626;1828.195593938676;1833.7249412588085;1953.4823271832834;1972;2026.6149431607948;65401;72526.63736681336;84541.57729211375;86667.56666216755;88632.55683721605;10810.945945270223;10975.560977560968;11097.72255693598;11233.438328083563;11392.487338069806;15871.28579213938;15891.841081589268;15905.522834314988;15914.124723140107;15924.76112858308;7985.5208687478935;8047.758567242992;8239.134890836376;8243.8351232975;8520.062793092755;7816.765497035098;7906.7627971160955;8026.879596806052;8038.799030024179;8079.151689072681;4016.477857878457;4181.330987042059;4650.09323181983;4684.437867455859;5000.149974504343;1791.9283228670968;1915.4349466907295;1953.4139758072522;1979.4655443030301;2016.133062782988;910.1626503616689;923.3859483443434;942.4533770413144;1013.1965102118241;1070.3631339353071;18740.15669294885;19959.501012474804;20221.191152353742;20266.59466810685;20304.187832486536;954.9331546791658;981.7251169672503;996.476849653923;1014.1937159957852;1019.0471908765328;10362.585496580052;10869.586953695969;10991.493487670017;11018.889811101786;11168.497417616227;418414;437882;447326;463097;464004;74343;90682.54658726664;92786.53606731923;93479;96684.51657741667;986.2652324018652;1058.0688993685508;1069.246181442097;1069.6309773128257;1083.707399002265;16243.593910151869;16256.918715406347;17541.21064552073;17758.46724598264;17965.820341796938;42531.36202956896;43035.569644303156;43426;43887.78056109699;44651.55348446474;53319;57429.13856292075;58387.12419313629;58728.11907821301;59981;7630.389568834591;7814.765557033298;7825.882611760714;8464.746057618282;8472.74581762548;12934.935325323313;13545.864541354855;13595.660108496972;13622.659433514242;13713.314334283046;11976.341301228516;12053.216540924812;12090.637280881587;12097.455614497192;12432.067594930235;11629;11832.70418239551;11888.524459021542;12087.274763514217;12377.257364558156;11994.340311282614;12150.817737733763;12297.44661490253;12331.876681233072;12639.747205056032;7960.4825686330205;8115.715949941724;8116.918830811616;8286.544240066854;8381.916180838114;10279.588816447558;11780.528778849093;11822.763544728885;12130.757384852077;12662.113652044269 12:26:54 INFO - PROCESS | 28779 | |3;mootools.html;1436.2316160853945;1507.8265999410069;1621.262325641833;1751.2819743905002;1765.9470215893527;8791.692290769824;9457.668981585637;9459.290553208502;9461.621535138604;9461.858072128924;7973.760787176379;8762.956185219082;8992.68525601604;9037.457752534852;9280.628774848998;2365.491419344845;2395.964060539093;2398.2565404724505;2398.3284680289457;2399.064364897691;2325.3721495196287;2349.7767712067325;2357.1278626907983;2360.551495215912;2361.929142125739;2312.5259321838985;2312.8034117100065;2315.1549684365255;2360.563295790276;2374.2165085521738;2316.3630001749484;2319.3273950554317;2327.441414060622;2329.9767002330013;2376.4890548531976;2318.188633978111;2323.7560056194193;2324.360800779782;2324.9070037198494;2325.5348930213854;9291.58187881547;9515.476648784314;9520.809583808285;9522.714318570384;9528.761780955463;5287.312649355597;5339.70631615261;5409.513143817036;5438.592105592057;5451.727413629303;3219.5170724391296;3297.241634424096;3351.949720754191;3365.0241429985276;3441.7074548663395;995.8655581496478;1047.5547892145885;1073.4579037586047;1086.7989421956963;1100.510272928547;13325.267110308929;13395.598132056053;13397.66505837362;13401.128926619836;13404.463821447229;4452.732836029849;4699.412573428322;4708.058388322349;4871.878203044952;4878.43897951733;11086.833697494543;11091.057260132819;11092.55629774816;11099.778004439866;11109.111271098292;5279.841604751864;5338.092524270884;5362.008028514737;5374.11327131019;5379.408265090837;4290.592393722592;4750.881227969328;4824.0834241494;4857.514248575114;4992.950070499249;3994.201159768058;4070.1656160487623;4123.670106391482;4156;4188.937165942513;1266.9683257918625;1296.1510210811932;1316.7300703355788;1321.8149459075737;1325.6884632111446;787.1813314153331;789.676232744572;886.499127992682;927.1284992107533;975.4244995452717;5685.1756495307945;5752.9712351437975;5803.419658034163;5832.096025116114;5844.152597873284;441.078146673454;443.4656239231718;448.1082645535361;454.64991956194206;462.9051044535862;3938;3969.503812023497;4006.358982562776;4010.578889216649;4043.353063509824;10979.61571345015;11679.591214307458;11780.352080635646;11835.585754498552;11882.049436045096;11425.485853136628;11695.415229238504;11713.765724685458;11770.764584708259;11776.823347649792;8531.95734021326;8634.913650863537;8644.524551149621;8664.393492455467;8700.042995270514;5684.9431505684415;5810.5061069809535;5824.097264923944;5841.50347220491;5882.911756323744;10434.843477347998;10671.199660025684;10686.25196236256;10689.786204276026;10693.091087257359;10934.617288394868;11022.173336999918;11043.226974111734;11052.723681907695;11064.834027489755;7408.703651853865;7997.400194985283;8183.672653094048;8294.253517183482;8379.371547134111;9133.08669133108;9310.0224476429;9311.953440232755;9315.068493150631;9320.161185493338;8008.11910689812;8194.590270486571;8204.671813127408;8310;8426;7955.721549745731;7982.441229113906;8023.919760802317;8063.55650439208;8083.797905052421;7427.326162581574;7428.591427471541;7442.330190282684;7468.887966680395;7513.1359893612935;5221.295125158055;5277.815276465305;5367.436419176087;5384.138537834085;5520.889582208413;4943.480934501969;5119.74401279942;5146.819861304836;5290.470952904679;5365.731713414391 12:26:54 INFO - PROCESS | 28779 | |4;prototype.html;310.4210647143079;318.72111902085675;323.40493491974763;324.54239522273593;326.8855900434848;5288.471152884715;5320.441353657869;5339.813106541272;5372.979133964544;5408.215808707735;4986.875328116799;5065.392152941652;5183.740812959346;5226.346706661668;5269.130593452076;4123.278426275405;4133.2353514599745;4135.607117323864;4149.688773341996;4163.916721665565;4150.796269081962;4151.522574903895;4160.9375859362135;4161.521425036114;4169.520505141902;3886.747361421513;3969.6030396960364;4035.59644035597;4118.238125946695;4144.48193975753;4109.828698820822;4156.729812562189;4158.397032430311;4162.271602469571;4171.937420938689;3692.667659910594;3696.8927711204624;3703.3148867459336;3711.3876210425246;3713.6472035156617;5010.924836127461;5076.492350764931;5106.540411362995;5188.766505507261;5192.454792246797;4727.503612120712;4776.785044672998;4777.928331075037;4785.3300537924715;4796.688215265996;5558.583106267006;5562.610617256793;5567.415421380778;5568.610197286192;5582.888342233173;3904.8438062477467;4038.9192216155807;4064.512258528966;4081.4898137732785;4095.3037983542868;5678.5741069419955;5698.572607054488;5699;5704.543636509069;5704.942950570483;4151.318715920057;4171.311733563928;4182.832686692559;4187.539370669224;4200.684948628804;3813.7902415366866;3989.9800500997308;4237.944596870515;4240.1307731914885;4240.512341080754;4640.83757068501;4647.907041859212;4650.814117364833;4651.116287905287;4707.482176960531;5541.392996031106;5551.9735296617255;5557;5575.609707320448;5577.832665020056;1325.5048483230998;1349.257908150533;1373.1623709537175;1376.7315373502272;1379.406855052337;887.245841035115;920.2913756407662;923.3075193604797;925.2320573923628;925.3522534226069;4759.452662943737;4782.976085119552;4784.354112194878;4811.230203167476;4819.807207711652;4621.838235661736;4989.825356112591;5032.496750325012;5076.36545431821;5094.038795063927;4657.0685862826895;4686.7656617169005;4688.343631891538;4697.530246975343;4740.985081340416;2640.2871224769206;2658.0861779518714;2667.9733202667726;2675.929628148756;2679.914634573001;4923.458419573844;5113.539781419616;5166.225066240019;5174.896502069938;5196;5070.340855688737;5084.288199652052;5108.386993560722;5116.641835071584;5124.743762811844;5114.923276150861;5130.6152038598;5149.253358263031;5151.351567498365;5183.50756678115;4918.532739389752;4934.901301973869;5205.323307969939;5608.242887210337;5649.745761440663;5614.943850561605;5619.775208991595;5635.323761148688;5647.802326918702;5658.886822263614;5658.632188907708;5911.55663325261;5928.8814223716145;5982.1325907744;5991.161237426851;3953.308171069994;3959.7822119783687;3966.2464131814863;3967.5437324706872;3967.980160099181;5400.432954539717;5515.255440515479;5658.886822263614;5674.2623458950075;5689.260396148474;5543.667379957215;5568.747031917786;5570.5543556516195;5599.552035837205;5600.887982240414;5266.526012658879;5529.364123125892;5598.608097433141;5604.607677462538;5605.607607467438;5455;5464.087569350695;5509.201165831012;5510.366307874646;5514.420985796594;5586.301712285965;5591.916121258266;5600.747966341443;5604.8318550443555;5625.971870140623;5233.618703489979;5442.891142177214;5451.264079349237;5475;5483.369412517612 12:26:54 INFO - PROCESS | 28779 | |5;yui.html;725.4051677624348;751.6204316820007;757.6514803190532;782.1708988472224;807.4791759315242;15394.307256173472;16543.917280413596;16639.33442662295;16654.50036498904;16678.166091695428;14824.407023719039;15004.699906001873;15005.249737513135;15019.774703379459;15021.248937553133;653.2455014458309;771.6758961236294;779.7465823607322;798.844225376049;807.9111297757242;803.5500119932827;806.3871457692137;806.818465845183;806.9435139540235;807.5760225881401;870.5734190246765;870.6212797433105;871.1375738019346;871.4596949891077;871.9258862996653;681.7068660475993;827.9668813247463;844.3835999720177;848.5248260973874;858.8582883824162;624.6314674342115;624.7376102037156;624.9281332646758;625.221599109112;625.2840497630233;14744.041637293541;14748.631284217872;14764.852351476562;14771.926140369336;14784;13421.194728316332;13576.660583485393;13659.248741319223;13678.52125175624;13802.309884505734;12899.935500322532;13071.41178646963;13224.206547607175;13258.73482530344;13549.051566390357;4481.103779244132;4514.616257618073;4542.06887588047;4666.895826794793;4706.152892479352;79428;80248;80671.596642017;81377;81593.18406815916;11662.533498660128;11834.940825295818;11875.643730688093;11993.760124797629;12349.814752778715;21954.670679939813;22293.442663933532;22331.66502502464;22426.439339016655;22449.775502245095;8315.126911674188;8512.106228846005;8561.700340488052;8579.957100214458;8687.044425113232;4661.696989695659;4810.326554282403;4858.489858564871;4928;4943;3422.6064002640014;3528.047427194643;3531.4173161428075;3531.5762108547137;3536.876209332661;594.5332913662841;596.9522438204933;607.9544034197453;616.6238594457377;623.8253289079065;579.3540202673988;663.7146027208347;704.5631708340759;714.417749534128;752.5597525447565;9180.6786762463;9316.906830931604;9661.016103356937;9872.457014864107;10227.833463756444;609.1562242251408;631.7851930343705;636.6084857812413;681.9011243369683;725.7459889038794;4348.2825333819565;4812.975935120302;4818.638602104857;4844.878878028077;4859.761039374256;126912.2889469976;127669;130010;131544.342278288;132445;109931.45034274778;111897.44051279692;112176;112819;113122.43438782918;85961;96644.51677741566;96848;97380.51309743406;97780.51109744549;8868.866966995629;9012;9019.368644194843;9162.496062716615;9227.215686666708;56019.439805601425;57098.714506427204;57271;58064;58747;68556.6572167136;73703.6314818444;74872.25127748653;76093.23906760862;84362;13321.13412628176;13926.373313200727;14052.297385130907;14165.866730661317;14365.712685746017;15642.765358519859;16007;16010.519684409486;16277.511674649779;16444.506664800076;14692.191929443983;14819.851801482278;14917;15169.469068582546;15202.315895784937;14697.191654459108;14887.404503819726;15072.472463463726;15244.3902243909;15543.37826486973;11654.76690466169;11696;11731.23746956445;14317.928410357881;14815.33331000086;11284.717882053013;11682.88317116852;11998.52042907547;12116.454759535665;12205;12412.441440135035;12450.25298482094;12453.626391208278;12725.363731813557;12802.423890925116 12:26:54 INFO - PROCESS | 28779 | __end_tp_report 12:26:54 INFO - PROCESS | 28779 | __start_cc_report 12:26:54 INFO - PROCESS | 28779 | _x_x_mozilla_cycle_collect,981 12:26:54 INFO - PROCESS | 28779 | __end_cc_report 12:26:54 INFO - PROCESS | 28779 | __startTimestamp1470770814874__endTimestamp 12:26:54 INFO - PROCESS | 28779 | 12:26:54 INFO - PROCESS | 28779 | ------- Summary: start ------- 12:26:54 INFO - PROCESS | 28779 | Number of tests: 6 12:26:54 INFO - PROCESS | 28779 | 12:26:54 INFO - PROCESS | 28779 | [#0] dojo.html Cycles:165 Average:5418.69 Median:5650.58 stddev:629.81 (11.1%) stddev-sans-first:604.83 12:26:54 INFO - PROCESS | 28779 | Values: 3096.9 3098.1 3125.0 3142.7 3147.7 5281.7 5376.2 5378.4 5491.1 5494.2 5395.0 5397.1 5402.6 5441.8 5475.8 4826.0 4907.9 4910.6 4914.3 5076.8 4988.2 5022.1 5039.8 5042.5 5054.5 4802.7 4852.4 4915.5 4926.6 4947.8 4903.2 4904.3 4905.0 4910.4 4949.6 4874.8 4927.6 4945.6 4957.5 4968.1 5302.1 5328.8 5374.9 5404.3 5478.0 5757.3 5796.6 5813.4 5815.3 5828.4 5853.1 5874.6 5876.0 5879.3 5889.0 5234.4 5352.0 5408.0 5411.2 5464.8 6045.7 6050.6 6088.1 6091.0 6098.6 5760.9 5763.6 5765.9 5797.1 5811.7 5582.1 5684.0 5737.3 5747.2 5903.8 5751.5 5802.4 5826.3 5839.4 5842.6 5779.3 5818.3 5826.7 5830.7 5842.2 4340.5 4391.0 4435.5 4444.4 4472.2 3955.8 3992.8 4005.9 4015.8 4072.5 5536.8 5543.6 5545.1 5592.2 5602.5 5702.0 5888.5 5893.4 5895.4 5902.1 5439.6 5457.5 5476.2 5489.6 5558.1 5026.0 5039.3 5042.0 5045.5 5080.8 5816.7 6032.7 6090.4 6098.3 6100.3 5782.5 5809.4 5861.6 5903.8 5957.7 6057.5 6099.8 6111.1 6111.8 6113.5 5563.9 5755.5 5760.0 5769.5 5772.9 5793.6 5822.8 5873.8 5914.0 5976.6 5724.5 5747.7 5748.0 5762.9 5767.4 5605.7 5738.6 5754.9 5755.6 5762.3 5569.6 5620.2 5650.6 5667.0 5726.6 5780.2 5811.3 5841.2 5854.9 5863.0 5419.9 5642.1 5650.8 5670.1 5670.7 12:26:54 INFO - PROCESS | 28779 | 12:26:54 INFO - PROCESS | 28779 | [#1] ext.html Cycles:180 Average:59803.64 Median:42916.18 stddev:112852.24 (263.0%) stddev-sans-first:113081.23 12:26:54 INFO - PROCESS | 28779 | Values: 585.8 602.5 605.8 608.5 620.0 33051.8 35530.6 35541.0 35554.5 35991.8 45158.5 46301.5 46358.0 49898.3 49908.8 8812.6 8953.6 8958.6 8958.7 8960.9 8763.1 8875.0 8890.5 8897.9 8898.0 8899.8 8933.2 8933.7 8936.1 8944.2 8847.1 8894.2 8896.7 8900.0 8914.1 8817.3 8882.1 8884.2 8891.9 8894.0 42903.8 43290.6 43306.4 43312.1 43322.6 3495.2 3574.1 3668.8 3882.7 4079.6 33009.2 33448.5 33612.8 34208.8 35015.6 4132.8 4149.3 4159.3 4357.6 4371.4 368196.0 370512.1 371011.0 371013.0 372613.0 61535.4 61737.4 61742.7 61785.4 61791.7 37506.6 64580.0 64734.0 64767.7 64818.4 16132.9 17637.6 17644.6 17647.1 17703.6 12639.4 12646.9 12662.0 12669.0 12676.9 19522.5 19538.0 19617.1 19698.7 19761.5 7004.2 7014.0 7086.3 7167.1 7172.4 923.3 1017.3 1035.6 1072.4 1143.6 30631.0 32275.4 32328.8 32400.4 32971.0 1044.0 1112.2 1128.9 1162.5 1187.0 16230.4 16889.8 16992.1 17014.6 17044.9 443491.0 448375.0 448496.0 448516.0 448625.0 58493.0 59014.7 59038.7 59061.7 59248.0 1216.3 1309.9 1325.6 1351.1 1388.8 32626.2 33553.3 34611.1 34740.0 35127.0 59888.0 60399.7 60409.4 60568.7 60577.0 452482.0 459362.0 460537.0 461022.0 461140.0 29467.7 30106.0 30195.2 31425.5 47353.0 50581.0 50765.4 51324.5 52198.0 52703.7 41269.8 41722.6 41972.4 42492.8 43753.6 41862.4 42056.2 42626.6 43433.8 44319.0 42081.4 42928.6 43034.6 43792.0 43993.3 35429.6 37150.6 37551.4 37648.8 38217.2 28401.2 28617.4 28690.6 28748.4 29099.7 12:26:54 INFO - PROCESS | 28779 | 12:26:54 INFO - PROCESS | 28779 | [#2] jquery.html Cycles:180 Average:27423.51 Median:396.94 stddev:73864.66 (18608.7%) stddev-sans-first:74043.91 12:26:54 INFO - PROCESS | 28779 | Values: 353.9 391.3 394.9 398.9 423.8 14203.4 16899.0 19445.7 19482.8 19636.3 18677.3 19206.5 19229.4 19406.2 19434.0 5306.9 5701.8 5882.6 5882.8 5884.5 5485.1 5616.9 5626.9 5630.3 5636.4 5570.9 5606.4 5621.5 5633.4 5723.8 5788.7 5828.2 5866.5 5872.1 5873.4 5576.9 5679.8 5686.5 5687.7 5705.5 12433.5 12643.2 12700.4 12725.0 12741.5 2266.6 2373.0 2520.3 2783.4 2903.0 9223.4 9237.3 9308.4 9350.7 9694.5 1828.2 1833.7 1953.5 1972.0 2026.6 65401.0 72526.6 84541.6 86667.6 88632.6 10810.9 10975.6 11097.7 11233.4 11392.5 15871.3 15891.8 15905.5 15914.1 15924.8 7985.5 8047.8 8239.1 8243.8 8520.1 7816.8 7906.8 8026.9 8038.8 8079.2 4016.5 4181.3 4650.1 4684.4 5000.1 1791.9 1915.4 1953.4 1979.5 2016.1 910.2 923.4 942.5 1013.2 1070.4 18740.2 19959.5 20221.2 20266.6 20304.2 954.9 981.7 996.5 1014.2 1019.0 10362.6 10869.6 10991.5 11018.9 11168.5 418414.0 437882.0 447326.0 463097.0 464004.0 74343.0 90682.5 92786.5 93479.0 96684.5 986.3 1058.1 1069.2 1069.6 1083.7 16243.6 16256.9 17541.2 17758.5 17965.8 42531.4 43035.6 43426.0 43887.8 44651.6 53319.0 57429.1 58387.1 58728.1 59981.0 7630.4 7814.8 7825.9 8464.7 8472.7 12934.9 13545.9 13595.7 13622.7 13713.3 11976.3 12053.2 12090.6 12097.5 12432.1 11629.0 11832.7 11888.5 12087.3 12377.3 11994.3 12150.8 12297.4 12331.9 12639.7 7960.5 8115.7 8116.9 8286.5 8381.9 10279.6 11780.5 11822.8 12130.8 12662.1 12:26:54 INFO - PROCESS | 28779 | 12:26:54 INFO - PROCESS | 28779 | [#3] mootools.html Cycles:180 Average:6042.35 Median:2581.16 stddev:3605.26 (139.7%) stddev-sans-first:3598.76 12:26:54 INFO - PROCESS | 28779 | Values: 1436.2 1507.8 1621.3 1751.3 1765.9 8791.7 9457.7 9459.3 9461.6 9461.9 7973.8 8763.0 8992.7 9037.5 9280.6 2365.5 2396.0 2398.3 2398.3 2399.1 2325.4 2349.8 2357.1 2360.6 2361.9 2312.5 2312.8 2315.2 2360.6 2374.2 2316.4 2319.3 2327.4 2330.0 2376.5 2318.2 2323.8 2324.4 2324.9 2325.5 9291.6 9515.5 9520.8 9522.7 9528.8 5287.3 5339.7 5409.5 5438.6 5451.7 3219.5 3297.2 3351.9 3365.0 3441.7 995.9 1047.6 1073.5 1086.8 1100.5 13325.3 13395.6 13397.7 13401.1 13404.5 4452.7 4699.4 4708.1 4871.9 4878.4 11086.8 11091.1 11092.6 11099.8 11109.1 5279.8 5338.1 5362.0 5374.1 5379.4 4290.6 4750.9 4824.1 4857.5 4993.0 3994.2 4070.2 4123.7 4156.0 4188.9 1267.0 1296.2 1316.7 1321.8 1325.7 787.2 789.7 886.5 927.1 975.4 5685.2 5753.0 5803.4 5832.1 5844.2 441.1 443.5 448.1 454.6 462.9 3938.0 3969.5 4006.4 4010.6 4043.4 10979.6 11679.6 11780.4 11835.6 11882.0 11425.5 11695.4 11713.8 11770.8 11776.8 8532.0 8634.9 8644.5 8664.4 8700.0 5684.9 5810.5 5824.1 5841.5 5882.9 10434.8 10671.2 10686.3 10689.8 10693.1 10934.6 11022.2 11043.2 11052.7 11064.8 7408.7 7997.4 8183.7 8294.3 8379.4 9133.1 9310.0 9312.0 9315.1 9320.2 8008.1 8194.6 8204.7 8310.0 8426.0 7955.7 7982.4 8023.9 8063.6 8083.8 7427.3 7428.6 7442.3 7468.9 7513.1 5221.3 5277.8 5367.4 5384.1 5520.9 4943.5 5119.7 5146.8 5290.5 5365.7 12:26:54 INFO - PROCESS | 28779 | 12:26:54 INFO - PROCESS | 28779 | [#4] prototype.html Cycles:180 Average:4538.78 Median:5076.43 stddev:1324.09 (26.1%) stddev-sans-first:1289.21 12:26:54 INFO - PROCESS | 28779 | Values: 310.4 318.7 323.4 324.5 326.9 5288.5 5320.4 5339.8 5373.0 5408.2 4986.9 5065.4 5183.7 5226.3 5269.1 4123.3 4133.2 4135.6 4149.7 4163.9 4150.8 4151.5 4160.9 4161.5 4169.5 3886.7 3969.6 4035.6 4118.2 4144.5 4109.8 4156.7 4158.4 4162.3 4171.9 3692.7 3696.9 3703.3 3711.4 3713.6 5010.9 5076.5 5106.5 5188.8 5192.5 4727.5 4776.8 4777.9 4785.3 4796.7 5558.6 5562.6 5567.4 5568.6 5582.9 3904.8 4038.9 4064.5 4081.5 4095.3 5678.6 5698.6 5699.0 5704.5 5704.9 4151.3 4171.3 4182.8 4187.5 4200.7 3813.8 3990.0 4237.9 4240.1 4240.5 4640.8 4647.9 4650.8 4651.1 4707.5 5541.4 5552.0 5557.0 5575.6 5577.8 1325.5 1349.3 1373.2 1376.7 1379.4 887.2 920.3 923.3 925.2 925.4 4759.5 4783.0 4784.4 4811.2 4819.8 4621.8 4989.8 5032.5 5076.4 5094.0 4657.1 4686.8 4688.3 4697.5 4741.0 2640.3 2658.1 2668.0 2675.9 2679.9 4923.5 5113.5 5166.2 5174.9 5196.0 5070.3 5084.3 5108.4 5116.6 5124.7 5114.9 5130.6 5149.3 5151.4 5183.5 4918.5 4934.9 5205.3 5608.2 5649.7 5614.9 5619.8 5635.3 5647.8 5658.9 5658.6 5911.6 5928.9 5982.1 5991.2 3953.3 3959.8 3966.2 3967.5 3968.0 5400.4 5515.3 5658.9 5674.3 5689.3 5543.7 5568.7 5570.6 5599.6 5600.9 5266.5 5529.4 5598.6 5604.6 5605.6 5455.0 5464.1 5509.2 5510.4 5514.4 5586.3 5591.9 5600.7 5604.8 5626.0 5233.6 5442.9 5451.3 5475.0 5483.4 12:26:54 INFO - PROCESS | 28779 | 12:26:54 INFO - PROCESS | 28779 | [#5] yui.html Cycles:180 Average:22397.04 Median:4664.30 stddev:33130.83 (710.3%) stddev-sans-first:33183.81 12:26:54 INFO - PROCESS | 28779 | Values: 725.4 751.6 757.7 782.2 807.5 15394.3 16543.9 16639.3 16654.5 16678.2 14824.4 15004.7 15005.2 15019.8 15021.2 653.2 771.7 779.7 798.8 807.9 803.6 806.4 806.8 806.9 807.6 870.6 870.6 871.1 871.5 871.9 681.7 828.0 844.4 848.5 858.9 624.6 624.7 624.9 625.2 625.3 14744.0 14748.6 14764.9 14771.9 14784.0 13421.2 13576.7 13659.2 13678.5 13802.3 12899.9 13071.4 13224.2 13258.7 13549.1 4481.1 4514.6 4542.1 4666.9 4706.2 79428.0 80248.0 80671.6 81377.0 81593.2 11662.5 11834.9 11875.6 11993.8 12349.8 21954.7 22293.4 22331.7 22426.4 22449.8 8315.1 8512.1 8561.7 8580.0 8687.0 4661.7 4810.3 4858.5 4928.0 4943.0 3422.6 3528.0 3531.4 3531.6 3536.9 594.5 597.0 608.0 616.6 623.8 579.4 663.7 704.6 714.4 752.6 9180.7 9316.9 9661.0 9872.5 10227.8 609.2 631.8 636.6 681.9 725.7 4348.3 4813.0 4818.6 4844.9 4859.8 126912.3 127669.0 130010.0 131544.3 132445.0 109931.5 111897.4 112176.0 112819.0 113122.4 85961.0 96644.5 96848.0 97380.5 97780.5 8868.9 9012.0 9019.4 9162.5 9227.2 56019.4 57098.7 57271.0 58064.0 58747.0 68556.7 73703.6 74872.3 76093.2 84362.0 13321.1 13926.4 14052.3 14165.9 14365.7 15642.8 16007.0 16010.5 16277.5 16444.5 14692.2 14819.9 14917.0 15169.5 15202.3 14697.2 14887.4 15072.5 15244.4 15543.4 11654.8 11696.0 11731.2 14317.9 14815.3 11284.7 11682.9 11998.5 12116.5 12205.0 12412.4 12450.3 12453.6 12725.4 12802.4 12:26:54 INFO - PROCESS | 28779 | -------- Summary: end -------- 12:26:54 INFO - PROCESS | 28779 | 12:26:55 INFO - TEST-INFO | 28779: exit 0 12:26:55 INFO - TEST-OK | dromaeo_css | took 1115540ms 12:26:55 INFO - TEST-START | kraken 12:26:55 INFO - operating with platform_type : linux_ 12:26:55 INFO - Initialising browser for kraken test... 12:26:55 INFO - TEST-INFO | started process 28977 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpX6Udvb/profile http://localhost:43692/getInfo.html) 12:26:59 INFO - PROCESS | 28977 | __metrics Screen width/height:1600/1200 12:26:59 INFO - PROCESS | 28977 | colorDepth:24 12:26:59 INFO - PROCESS | 28977 | Browser inner width/height: 1024/697 12:26:59 INFO - PROCESS | 28977 | __metrics 12:27:00 INFO - PROCESS | 28977 | 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" 12:27:00 INFO - PROCESS | 28977 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:27:01 INFO - TEST-INFO | 28977: exit 0 12:27:01 INFO - Browser initialized. 12:27:01 INFO - Running cycle 1/1 for kraken test... 12:27:01 INFO - TEST-INFO | started process 29057 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpX6Udvb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:27:13 INFO - PROCESS | 29057 | RSS: Main: 155570176 12:27:13 INFO - PROCESS | 29057 | 12:27:15 INFO - PROCESS | 29057 | Cycle 1(1): loaded http://localhost:43692/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43692/tests/kraken/driver.html?testName=audio-beat-detection) 12:27:16 INFO - PROCESS | 29057 | RSS: Main: 211480576 12:27:16 INFO - PROCESS | 29057 | 12:27:19 INFO - PROCESS | 29057 | Cycle 1(1): loaded http://localhost:43692/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43692/tests/kraken/driver.html?testName=audio-dft) 12:27:19 INFO - PROCESS | 29057 | RSS: Main: 191496192 12:27:19 INFO - PROCESS | 29057 | 12:27:23 INFO - PROCESS | 29057 | Cycle 1(1): loaded http://localhost:43692/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43692/tests/kraken/driver.html?testName=audio-fft) 12:27:23 INFO - PROCESS | 29057 | RSS: Main: 268259328 12:27:23 INFO - PROCESS | 29057 | 12:27:25 INFO - PROCESS | 29057 | Cycle 1(1): loaded http://localhost:43692/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43692/tests/kraken/driver.html?testName=audio-oscillator) 12:27:26 INFO - PROCESS | 29057 | RSS: Main: 178679808 12:27:26 INFO - PROCESS | 29057 | 12:27:28 INFO - PROCESS | 29057 | Cycle 1(1): loaded http://localhost:43692/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43692/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:27:29 INFO - PROCESS | 29057 | RSS: Main: 183197696 12:27:29 INFO - PROCESS | 29057 | 12:27:32 INFO - PROCESS | 29057 | Cycle 1(1): loaded http://localhost:43692/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43692/tests/kraken/driver.html?testName=imaging-darkroom) 12:27:32 INFO - PROCESS | 29057 | RSS: Main: 217989120 12:27:32 INFO - PROCESS | 29057 | 12:27:36 INFO - PROCESS | 29057 | Cycle 1(1): loaded http://localhost:43692/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43692/tests/kraken/driver.html?testName=imaging-desaturate) 12:27:36 INFO - PROCESS | 29057 | RSS: Main: 219041792 12:27:36 INFO - PROCESS | 29057 | 12:27:39 INFO - PROCESS | 29057 | Cycle 1(1): loaded http://localhost:43692/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43692/tests/kraken/driver.html?testName=json-parse-financial) 12:27:40 INFO - PROCESS | 29057 | RSS: Main: 218742784 12:27:40 INFO - PROCESS | 29057 | 12:27:41 INFO - PROCESS | 29057 | Cycle 1(1): loaded http://localhost:43692/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43692/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:27:42 INFO - PROCESS | 29057 | RSS: Main: 180994048 12:27:42 INFO - PROCESS | 29057 | 12:27:43 INFO - PROCESS | 29057 | Cycle 1(1): loaded http://localhost:43692/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43692/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:27:44 INFO - PROCESS | 29057 | RSS: Main: 188174336 12:27:44 INFO - PROCESS | 29057 | 12:27:46 INFO - PROCESS | 29057 | Cycle 1(1): loaded http://localhost:43692/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43692/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:27:47 INFO - PROCESS | 29057 | RSS: Main: 192929792 12:27:47 INFO - PROCESS | 29057 | 12:27:49 INFO - PROCESS | 29057 | Cycle 1(1): loaded http://localhost:43692/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43692/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:27:50 INFO - PROCESS | 29057 | RSS: Main: 188735488 12:27:50 INFO - PROCESS | 29057 | 12:27:53 INFO - PROCESS | 29057 | Cycle 1(1): loaded http://localhost:43692/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43692/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:27:53 INFO - PROCESS | 29057 | RSS: Main: 191692800 12:27:53 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | Cycle 1(1): loaded http://localhost:43692/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43692/tests/kraken/driver.html?testName=ai-astar) 12:27:55 INFO - PROCESS | 29057 | RSS: Main: 242253824 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | __start_tp_report 12:27:55 INFO - PROCESS | 29057 | _x_x_mozilla_page_load 12:27:55 INFO - PROCESS | 29057 | _x_x_mozilla_page_load_details 12:27:55 INFO - PROCESS | 29057 | |i|pagename|runs| 12:27:55 INFO - PROCESS | 29057 | |0;ai-astar;103;111;112;111;111;112;112;111;110;111 12:27:55 INFO - PROCESS | 29057 | |1;audio-beat-detection;145;120;120;172;118;119;150;121;152;117 12:27:55 INFO - PROCESS | 29057 | |2;audio-dft;159;160;166;160;161;159;159;161;161;161 12:27:55 INFO - PROCESS | 29057 | |3;audio-fft;73;75;76;104;74;76;75;75;105;74 12:27:55 INFO - PROCESS | 29057 | |4;audio-oscillator;84;84;125;84;84;84;119;84;85;128 12:27:55 INFO - PROCESS | 29057 | |5;imaging-gaussian-blur;116;116;116;117;116;117;117;117;117;116 12:27:55 INFO - PROCESS | 29057 | |6;imaging-darkroom;145;145;145;145;145;146;145;146;146;145 12:27:55 INFO - PROCESS | 29057 | |7;imaging-desaturate;95;95;95;94;95;95;96;94;95;94 12:27:55 INFO - PROCESS | 29057 | |8;json-parse-financial;50;50;51;49;50;51;51;50;50;56 12:27:55 INFO - PROCESS | 29057 | |9;json-stringify-tinderbox;61;59;60;61;61;59;60;60;60;61 12:27:55 INFO - PROCESS | 29057 | |10;stanford-crypto-aes;86;78;76;75;74;76;75;75;75;75 12:27:55 INFO - PROCESS | 29057 | |11;stanford-crypto-ccm;121;123;121;130;126;121;127;126;141;129 12:27:55 INFO - PROCESS | 29057 | |12;stanford-crypto-pbkdf2;156;157;155;157;156;162;155;155;156;155 12:27:55 INFO - PROCESS | 29057 | |13;stanford-crypto-sha256-iterative;66;63;67;64;62;64;61;62;69;61 12:27:55 INFO - PROCESS | 29057 | __end_tp_report 12:27:55 INFO - PROCESS | 29057 | __start_cc_report 12:27:55 INFO - PROCESS | 29057 | _x_x_mozilla_cycle_collect,1633 12:27:55 INFO - PROCESS | 29057 | __end_cc_report 12:27:55 INFO - PROCESS | 29057 | __startTimestamp1470770875521__endTimestamp 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | ------- Summary: start ------- 12:27:55 INFO - PROCESS | 29057 | Number of tests: 14 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:2.67 (2.4%) stddev-sans-first:0.67 12:27:55 INFO - PROCESS | 29057 | Values: 103.0 111.0 112.0 111.0 111.0 112.0 112.0 111.0 110.0 111.0 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | [#1] audio-beat-detection Cycles:10 Average:133.40 Median:133.00 stddev:19.64 (14.8%) stddev-sans-first:20.38 12:27:55 INFO - PROCESS | 29057 | Values: 145.0 120.0 120.0 172.0 118.0 119.0 150.0 121.0 152.0 117.0 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:2.06 (1.3%) stddev-sans-first:2.09 12:27:55 INFO - PROCESS | 29057 | Values: 159.0 160.0 166.0 160.0 161.0 159.0 159.0 161.0 161.0 161.0 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | [#3] audio-fft Cycles:10 Average:80.70 Median:75.00 stddev:12.58 (16.8%) stddev-sans-first:13.03 12:27:55 INFO - PROCESS | 29057 | Values: 73.0 75.0 76.0 104.0 74.0 76.0 75.0 75.0 105.0 74.0 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:84.00 stddev:19.38 (23.1%) stddev-sans-first:20.05 12:27:55 INFO - PROCESS | 29057 | Values: 84.0 84.0 125.0 84.0 84.0 84.0 119.0 84.0 85.0 128.0 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 12:27:55 INFO - PROCESS | 29057 | Values: 116.0 116.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 12:27:55 INFO - PROCESS | 29057 | Values: 145.0 145.0 145.0 145.0 145.0 146.0 145.0 146.0 146.0 145.0 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 12:27:55 INFO - PROCESS | 29057 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 96.0 94.0 95.0 94.0 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:50.50 stddev:1.93 (3.8%) stddev-sans-first:2.03 12:27:55 INFO - PROCESS | 29057 | Values: 50.0 50.0 51.0 49.0 50.0 51.0 51.0 50.0 50.0 56.0 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.50 stddev:0.79 (1.3%) stddev-sans-first:0.78 12:27:55 INFO - PROCESS | 29057 | Values: 61.0 59.0 60.0 61.0 61.0 59.0 60.0 60.0 60.0 61.0 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | [#10] stanford-crypto-aes Cycles:10 Average:76.50 Median:75.50 stddev:3.50 (4.6%) stddev-sans-first:1.13 12:27:55 INFO - PROCESS | 29057 | Values: 86.0 78.0 76.0 75.0 74.0 76.0 75.0 75.0 75.0 75.0 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:126.50 stddev:6.08 (4.8%) stddev-sans-first:6.11 12:27:55 INFO - PROCESS | 29057 | Values: 121.0 123.0 121.0 130.0 126.0 121.0 127.0 126.0 141.0 129.0 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.40 Median:156.00 stddev:2.12 (1.4%) stddev-sans-first:2.24 12:27:55 INFO - PROCESS | 29057 | Values: 156.0 157.0 155.0 157.0 156.0 162.0 155.0 155.0 156.0 155.0 12:27:55 INFO - PROCESS | 29057 | 12:27:55 INFO - PROCESS | 29057 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:64.00 stddev:2.69 (4.2%) stddev-sans-first:2.74 12:27:55 INFO - PROCESS | 29057 | Values: 66.0 63.0 67.0 64.0 62.0 64.0 61.0 62.0 69.0 61.0 12:27:55 INFO - PROCESS | 29057 | -------- Summary: end -------- 12:27:55 INFO - PROCESS | 29057 | 12:27:56 INFO - TEST-INFO | 29057: exit 0 12:27:56 INFO - TEST-OK | kraken | took 60887ms 12:27:56 INFO - SUITE-END | took 1176s 12:27:56 INFO - Completed test suite (00:19:36) 12:27:56 INFO - javascript benchmark 12:27:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3096.9380612387754, 3098.148009297443, 3125.0312403155017, 3142.6700196479383, 3147.7009684080017, 5281.735913204344, 5376.247325374441, 5378.408375078743, 5491.066518691822, 5494.1758736189595, 5395.0, 5397.136458166694, 5402.594805389602, 5441.782328706847, 5475.753591088401, 4825.951740482594, 4907.926381104268, 4910.607151427877, 4914.262860570925, 5076.822311219108, 4988.226824842137, 5022.146235140017, 5039.798408063673, 5042.495750424947, 5054.494550544953, 4802.711837289774, 4852.417709874802, 4915.483874193194, 4926.55660990509, 4947.802087916479, 4903.239997800347, 4904.264360345941, 4904.950950490485, 4910.361652985125, 4949.620469210825, 4874.780634871404, 4927.556519913225, 4945.629077819142, 4957.454679985199, 4968.130577149021, 5302.098643230622, 5328.813491527778, 5374.919376209321, 5404.270423492818, 5478.041342765, 5757.309122905236, 5796.565257605696, 5813.447722466359, 5815.302163740336, 5828.417158284137, 5853.09277062056, 5874.618149820248, 5876.030454974924, 5879.323877754027, 5889.02831032879, 5234.42421333653, 5351.956368508181, 5408.0, 5411.215373770781, 5464.754086066137, 6045.703648029152, 6050.637314582524, 6088.1476593276975, 6091.02543593023, 6098.573099883054, 5760.884782304331, 5763.567732420001, 5765.942340576625, 5797.101449275369, 5811.651300921958, 5582.106862901916, 5684.0, 5737.254156959652, 5747.166660834239, 5903.763849445974, 5751.4536119068625, 5802.361740208574, 5826.271716035496, 5839.386864379264, 5842.5618078644275, 5779.2775903012125, 5818.272715910512, 5826.70866456684, 5830.650160990354, 5842.2405087338375, 4340.500842403101, 4391.012022294958, 4435.489918659331, 4444.355568442623, 4472.195004899084, 3955.7824319662695, 3992.780397078189, 4005.939910901339, 4015.759054456742, 4072.490938632671, 5536.778528858881, 5543.584231182596, 5545.140503222007, 5592.217089607459, 5602.495775380154, 5702.030654788696, 5888.5289176865745, 5893.381194974551, 5895.35151133375, 5902.114682797529, 5439.592030597721, 5457.454254574511, 5476.151196564539, 5489.615726899079, 5558.082916318843, 5025.969676216367, 5039.294498770175, 5042.0, 5045.520675535819, 5080.847574572768, 5816.651000939957, 6032.698365081728, 6090.421409966223, 6098.32918378978, 6100.328963813977, 5782.45066718661, 5809.360970293349, 5861.589688721749, 5903.793367232126, 5957.7319020645045, 6057.545684073624, 6099.75600975956, 6111.144439778345, 6111.816645500642, 6113.51091912637, 5563.860903477445, 5755.453231942959, 5760.020796464695, 5769.509591684754, 5772.913406298825, 5793.623414478046, 5822.82531524055, 5873.794417195378, 5914.02418600926, 5976.641401515923, 5724.4561766632105, 5747.655140691572, 5748.0, 5762.913556296575, 5767.423257674199, 5605.691686957257, 5738.598298119092, 5754.884902302014, 5755.5683323750045, 5762.337331206797, 5569.637973531708, 5620.213170156099, 5650.57620678459, 5666.971665141813, 5726.599138060463, 5780.2485676863425, 5811.331696854916, 5841.240638716939, 5854.912176317273, 5863.0, 5419.864503387289, 5642.097264437671, 5650.802221922378, 5670.149477578396, 5670.659760414388], "value": 5375.274175603774, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.7510558012843, 602.46972689369, 605.8394525450755, 608.5010291560922, 619.9783007594732, 33051.83474082629, 35530.644693553055, 35540.99999999997, 35554.46668299977, 35991.820040899795, 45158.54841451585, 46301.53698463014, 46358.0, 49898.251526227046, 49908.750456247755, 8812.559372031405, 8953.597088131017, 8958.641654333851, 8958.686445974392, 8960.910390896073, 8763.123687631218, 8874.955625221864, 8890.466572005667, 8897.911020889773, 8898.0, 8899.822003559924, 8933.240674542672, 8933.731988040368, 8936.061713520085, 8944.23973962214, 8847.071057538962, 8894.199522042982, 8896.688615898413, 8900.0, 8914.064023277528, 8817.29461643067, 8882.067382924764, 8884.156005179562, 8891.911080889173, 8894.021657617657, 42903.785481072395, 43290.567094329286, 43306.35040474396, 43312.13375732468, 43322.56677433248, 3495.2310491691787, 3574.0528759878653, 3668.761530500509, 3882.650561449483, 4079.5920407958965, 33009.174770630685, 33448.49827252593, 33612.83193584041, 34208.82895585531, 35015.649843501495, 4132.772697501636, 4149.33610622299, 4159.313713237312, 4357.564243575649, 4371.366151907955, 368196.0, 370512.1474392611, 371011.0, 371013.0, 372613.0, 61535.38464615386, 61737.38262617406, 61742.69128654418, 61785.38214617886, 61791.6910415445, 37506.62493375031, 64580.0, 64734.0, 64767.676161619835, 64818.35181648217, 16132.919335403483, 17637.559061023578, 17644.558886027953, 17647.117644118, 17703.645927081645, 12639.43122559487, 12646.873531264753, 12662.0, 12669.049821263443, 12676.936615316865, 19522.467004316088, 19538.0, 19617.117229724696, 19698.70451943222, 19761.50596235077, 7004.198320671772, 7013.964930175316, 7086.255943125898, 7167.104111986002, 7172.390346820474, 923.2660035271975, 1017.3488967061087, 1035.5805898611075, 1072.367303291062, 1143.5711608146944, 30631.01604278075, 32275.35449291048, 32328.83835580807, 32400.35199296048, 32971.0, 1043.9798988956393, 1112.2436743014828, 1128.937908415045, 1162.4826952006445, 1186.976260474803, 16230.431934882223, 16889.831101689073, 16992.06543640087, 17014.57463563421, 17044.914775426045, 443491.0, 448375.0, 448496.0, 448516.0, 448625.0, 58493.0, 59014.70492647595, 59038.70480647569, 59061.70469147627, 59247.99999999914, 1216.3006271393983, 1309.9083064185606, 1325.5890673891124, 1351.067763243359, 1388.7916812478209, 32626.184345391554, 33553.32893342168, 34611.13472163216, 34740.0, 35127.0, 59888.0, 60399.698001509714, 60409.39590604038, 60568.6971565157, 60577.0, 452482.0, 459362.0, 460537.0, 461022.0, 461140.0, 29467.705322946495, 30106.032504637365, 30195.245118872204, 31425.52861707122, 47353.0, 50581.0, 50765.40858983305, 51324.48675513197, 52198.0, 52703.73648131735, 41269.793651031556, 41722.58277417308, 41972.37041444442, 42492.78753606212, 43753.56246437622, 41862.37206441845, 42056.158876822905, 42626.57373426226, 43433.78283108564, 44319.0, 42081.36877946895, 42928.57071429246, 43034.56965430431, 43792.0, 43993.34009989789, 35429.64570354367, 37150.58521196114, 37551.43672844964, 37648.811755941046, 38217.23565528618, 28401.171800794327, 28617.427651446436, 28690.56964145498, 28748.425031498835, 29099.7090029097], "value": 20488.67571610952, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.93983022886107, 391.32301119064, 394.9368101103823, 398.934175860983, 423.7669281894957, 14203.360848761804, 16899.0, 19445.708314375297, 19482.805171948276, 19636.312729054487, 18677.346292879753, 19206.46348292137, 19229.42311730647, 19406.22375104994, 19434.0, 5306.92039619406, 5701.828945131642, 5882.617629854067, 5882.794102206423, 5884.4703976642095, 5485.149801780711, 5616.887662246752, 5626.859328516779, 5630.296212973379, 5636.436356364372, 5570.878097836358, 5606.439356064381, 5621.4659607337235, 5633.352164501073, 5723.799667011655, 5788.710564471759, 5828.242328497311, 5866.5306775458075, 5872.119182122673, 5873.383294754075, 5576.9163462548095, 5679.829605111853, 5686.516646085088, 5687.658740475585, 5705.515031222351, 12433.502659893593, 12643.241405515699, 12700.42848071839, 12725.0, 12741.490340386374, 2266.5806825737127, 2372.9644055339013, 2520.3195137312823, 2783.401568662747, 2902.956455653167, 9223.400478968913, 9237.30720195988, 9308.394954328014, 9350.67272645461, 9694.51527423626, 1828.195593938676, 1833.7249412588085, 1953.4823271832834, 1972.0, 2026.6149431607948, 65401.0, 72526.63736681336, 84541.57729211375, 86667.56666216755, 88632.55683721605, 10810.945945270223, 10975.560977560968, 11097.72255693598, 11233.438328083563, 11392.487338069806, 15871.28579213938, 15891.841081589268, 15905.522834314988, 15914.124723140107, 15924.76112858308, 7985.5208687478935, 8047.758567242992, 8239.134890836376, 8243.8351232975, 8520.062793092755, 7816.765497035098, 7906.7627971160955, 8026.879596806052, 8038.799030024179, 8079.151689072681, 4016.477857878457, 4181.330987042059, 4650.09323181983, 4684.437867455859, 5000.149974504343, 1791.9283228670968, 1915.4349466907295, 1953.4139758072522, 1979.4655443030301, 2016.133062782988, 910.1626503616689, 923.3859483443434, 942.4533770413144, 1013.1965102118241, 1070.3631339353071, 18740.15669294885, 19959.501012474804, 20221.191152353742, 20266.59466810685, 20304.187832486536, 954.9331546791658, 981.7251169672503, 996.476849653923, 1014.1937159957852, 1019.0471908765328, 10362.585496580052, 10869.586953695969, 10991.493487670017, 11018.889811101786, 11168.497417616227, 418414.0, 437882.0, 447326.0, 463097.0, 464004.0, 74343.0, 90682.54658726664, 92786.53606731923, 93479.0, 96684.51657741667, 986.2652324018652, 1058.0688993685508, 1069.246181442097, 1069.6309773128257, 1083.707399002265, 16243.593910151869, 16256.918715406347, 17541.21064552073, 17758.46724598264, 17965.820341796938, 42531.36202956896, 43035.569644303156, 43426.0, 43887.78056109699, 44651.55348446474, 53319.0, 57429.13856292075, 58387.12419313629, 58728.11907821301, 59981.0, 7630.389568834591, 7814.765557033298, 7825.882611760714, 8464.746057618282, 8472.74581762548, 12934.935325323313, 13545.864541354855, 13595.660108496972, 13622.659433514242, 13713.314334283046, 11976.341301228516, 12053.216540924812, 12090.637280881587, 12097.455614497192, 12432.067594930235, 11629.0, 11832.70418239551, 11888.524459021542, 12087.274763514217, 12377.257364558156, 11994.340311282614, 12150.817737733763, 12297.44661490253, 12331.876681233072, 12639.747205056032, 7960.4825686330205, 8115.715949941724, 8116.918830811616, 8286.544240066854, 8381.916180838114, 10279.588816447558, 11780.528778849093, 11822.763544728885, 12130.757384852077, 12662.113652044269], "value": 9042.407492257531, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1436.2316160853945, 1507.8265999410069, 1621.262325641833, 1751.2819743905002, 1765.9470215893527, 8791.692290769824, 9457.668981585637, 9459.290553208502, 9461.621535138604, 9461.858072128924, 7973.760787176379, 8762.956185219082, 8992.68525601604, 9037.457752534852, 9280.628774848998, 2365.491419344845, 2395.964060539093, 2398.2565404724505, 2398.3284680289457, 2399.064364897691, 2325.3721495196287, 2349.7767712067325, 2357.1278626907983, 2360.551495215912, 2361.929142125739, 2312.5259321838985, 2312.8034117100065, 2315.1549684365255, 2360.563295790276, 2374.2165085521738, 2316.3630001749484, 2319.3273950554317, 2327.441414060622, 2329.9767002330013, 2376.4890548531976, 2318.188633978111, 2323.7560056194193, 2324.360800779782, 2324.9070037198494, 2325.5348930213854, 9291.58187881547, 9515.476648784314, 9520.809583808285, 9522.714318570384, 9528.761780955463, 5287.312649355597, 5339.70631615261, 5409.513143817036, 5438.592105592057, 5451.727413629303, 3219.5170724391296, 3297.241634424096, 3351.949720754191, 3365.0241429985276, 3441.7074548663395, 995.8655581496478, 1047.5547892145885, 1073.4579037586047, 1086.7989421956963, 1100.510272928547, 13325.267110308929, 13395.598132056053, 13397.66505837362, 13401.128926619836, 13404.463821447229, 4452.732836029849, 4699.412573428322, 4708.058388322349, 4871.878203044952, 4878.43897951733, 11086.833697494543, 11091.057260132819, 11092.55629774816, 11099.778004439866, 11109.111271098292, 5279.841604751864, 5338.092524270884, 5362.008028514737, 5374.11327131019, 5379.408265090837, 4290.592393722592, 4750.881227969328, 4824.0834241494, 4857.514248575114, 4992.950070499249, 3994.201159768058, 4070.1656160487623, 4123.670106391482, 4156.0, 4188.937165942513, 1266.9683257918625, 1296.1510210811932, 1316.7300703355788, 1321.8149459075737, 1325.6884632111446, 787.1813314153331, 789.676232744572, 886.499127992682, 927.1284992107533, 975.4244995452717, 5685.1756495307945, 5752.9712351437975, 5803.419658034163, 5832.096025116114, 5844.152597873284, 441.078146673454, 443.4656239231718, 448.1082645535361, 454.64991956194206, 462.9051044535862, 3938.0, 3969.503812023497, 4006.358982562776, 4010.578889216649, 4043.353063509824, 10979.61571345015, 11679.591214307458, 11780.352080635646, 11835.585754498552, 11882.049436045096, 11425.485853136628, 11695.415229238504, 11713.765724685458, 11770.764584708259, 11776.823347649792, 8531.95734021326, 8634.913650863537, 8644.524551149621, 8664.393492455467, 8700.042995270514, 5684.9431505684415, 5810.5061069809535, 5824.097264923944, 5841.50347220491, 5882.911756323744, 10434.843477347998, 10671.199660025684, 10686.25196236256, 10689.786204276026, 10693.091087257359, 10934.617288394868, 11022.173336999918, 11043.226974111734, 11052.723681907695, 11064.834027489755, 7408.703651853865, 7997.400194985283, 8183.672653094048, 8294.253517183482, 8379.371547134111, 9133.08669133108, 9310.0224476429, 9311.953440232755, 9315.068493150631, 9320.161185493338, 8008.11910689812, 8194.590270486571, 8204.671813127408, 8310.0, 8426.0, 7955.721549745731, 7982.441229113906, 8023.919760802317, 8063.55650439208, 8083.797905052421, 7427.326162581574, 7428.591427471541, 7442.330190282684, 7468.887966680395, 7513.1359893612935, 5221.295125158055, 5277.815276465305, 5367.436419176087, 5384.138537834085, 5520.889582208413, 4943.480934501969, 5119.74401279942, 5146.819861304836, 5290.470952904679, 5365.731713414391], "value": 4665.858461045181, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.4210647143079, 318.72111902085675, 323.40493491974763, 324.54239522273593, 326.8855900434848, 5288.471152884715, 5320.441353657869, 5339.813106541272, 5372.979133964544, 5408.215808707735, 4986.875328116799, 5065.392152941652, 5183.740812959346, 5226.346706661668, 5269.130593452076, 4123.278426275405, 4133.2353514599745, 4135.607117323864, 4149.688773341996, 4163.916721665565, 4150.796269081962, 4151.522574903895, 4160.9375859362135, 4161.521425036114, 4169.520505141902, 3886.747361421513, 3969.6030396960364, 4035.59644035597, 4118.238125946695, 4144.48193975753, 4109.828698820822, 4156.729812562189, 4158.397032430311, 4162.271602469571, 4171.937420938689, 3692.667659910594, 3696.8927711204624, 3703.3148867459336, 3711.3876210425246, 3713.6472035156617, 5010.924836127461, 5076.492350764931, 5106.540411362995, 5188.766505507261, 5192.454792246797, 4727.503612120712, 4776.785044672998, 4777.928331075037, 4785.3300537924715, 4796.688215265996, 5558.583106267006, 5562.610617256793, 5567.415421380778, 5568.610197286192, 5582.888342233173, 3904.8438062477467, 4038.9192216155807, 4064.512258528966, 4081.4898137732785, 4095.3037983542868, 5678.5741069419955, 5698.572607054488, 5699.0, 5704.543636509069, 5704.942950570483, 4151.318715920057, 4171.311733563928, 4182.832686692559, 4187.539370669224, 4200.684948628804, 3813.7902415366866, 3989.9800500997308, 4237.944596870515, 4240.1307731914885, 4240.512341080754, 4640.83757068501, 4647.907041859212, 4650.814117364833, 4651.116287905287, 4707.482176960531, 5541.392996031106, 5551.9735296617255, 5557.0, 5575.609707320448, 5577.832665020056, 1325.5048483230998, 1349.257908150533, 1373.1623709537175, 1376.7315373502272, 1379.406855052337, 887.245841035115, 920.2913756407662, 923.3075193604797, 925.2320573923628, 925.3522534226069, 4759.452662943737, 4782.976085119552, 4784.354112194878, 4811.230203167476, 4819.807207711652, 4621.838235661736, 4989.825356112591, 5032.496750325012, 5076.36545431821, 5094.038795063927, 4657.0685862826895, 4686.7656617169005, 4688.343631891538, 4697.530246975343, 4740.985081340416, 2640.2871224769206, 2658.0861779518714, 2667.9733202667726, 2675.929628148756, 2679.914634573001, 4923.458419573844, 5113.539781419616, 5166.225066240019, 5174.896502069938, 5196.0, 5070.340855688737, 5084.288199652052, 5108.386993560722, 5116.641835071584, 5124.743762811844, 5114.923276150861, 5130.6152038598, 5149.253358263031, 5151.351567498365, 5183.50756678115, 4918.532739389752, 4934.901301973869, 5205.323307969939, 5608.242887210337, 5649.745761440663, 5614.943850561605, 5619.775208991595, 5635.323761148688, 5647.802326918702, 5658.886822263614, 5658.632188907708, 5911.55663325261, 5928.8814223716145, 5982.1325907744, 5991.161237426851, 3953.308171069994, 3959.7822119783687, 3966.2464131814863, 3967.5437324706872, 3967.980160099181, 5400.432954539717, 5515.255440515479, 5658.886822263614, 5674.2623458950075, 5689.260396148474, 5543.667379957215, 5568.747031917786, 5570.5543556516195, 5599.552035837205, 5600.887982240414, 5266.526012658879, 5529.364123125892, 5598.608097433141, 5604.607677462538, 5605.607607467438, 5455.0, 5464.087569350695, 5509.201165831012, 5510.366307874646, 5514.420985796594, 5586.301712285965, 5591.916121258266, 5600.747966341443, 5604.8318550443555, 5625.971870140623, 5233.618703489979, 5442.891142177214, 5451.264079349237, 5475.0, 5483.369412517612], "value": 4113.985757645546, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.4051677624348, 751.6204316820007, 757.6514803190532, 782.1708988472224, 807.4791759315242, 15394.307256173472, 16543.917280413596, 16639.33442662295, 16654.50036498904, 16678.166091695428, 14824.407023719039, 15004.699906001873, 15005.249737513135, 15019.774703379459, 15021.248937553133, 653.2455014458309, 771.6758961236294, 779.7465823607322, 798.844225376049, 807.9111297757242, 803.5500119932827, 806.3871457692137, 806.818465845183, 806.9435139540235, 807.5760225881401, 870.5734190246765, 870.6212797433105, 871.1375738019346, 871.4596949891077, 871.9258862996653, 681.7068660475993, 827.9668813247463, 844.3835999720177, 848.5248260973874, 858.8582883824162, 624.6314674342115, 624.7376102037156, 624.9281332646758, 625.221599109112, 625.2840497630233, 14744.041637293541, 14748.631284217872, 14764.852351476562, 14771.926140369336, 14784.0, 13421.194728316332, 13576.660583485393, 13659.248741319223, 13678.52125175624, 13802.309884505734, 12899.935500322532, 13071.41178646963, 13224.206547607175, 13258.73482530344, 13549.051566390357, 4481.103779244132, 4514.616257618073, 4542.06887588047, 4666.895826794793, 4706.152892479352, 79428.0, 80248.0, 80671.596642017, 81377.0, 81593.18406815916, 11662.533498660128, 11834.940825295818, 11875.643730688093, 11993.760124797629, 12349.814752778715, 21954.670679939813, 22293.442663933532, 22331.66502502464, 22426.439339016655, 22449.775502245095, 8315.126911674188, 8512.106228846005, 8561.700340488052, 8579.957100214458, 8687.044425113232, 4661.696989695659, 4810.326554282403, 4858.489858564871, 4928.0, 4943.0, 3422.6064002640014, 3528.047427194643, 3531.4173161428075, 3531.5762108547137, 3536.876209332661, 594.5332913662841, 596.9522438204933, 607.9544034197453, 616.6238594457377, 623.8253289079065, 579.3540202673988, 663.7146027208347, 704.5631708340759, 714.417749534128, 752.5597525447565, 9180.6786762463, 9316.906830931604, 9661.016103356937, 9872.457014864107, 10227.833463756444, 609.1562242251408, 631.7851930343705, 636.6084857812413, 681.9011243369683, 725.7459889038794, 4348.2825333819565, 4812.975935120302, 4818.638602104857, 4844.878878028077, 4859.761039374256, 126912.2889469976, 127669.0, 130010.0, 131544.342278288, 132445.0, 109931.45034274778, 111897.44051279692, 112176.0, 112819.0, 113122.43438782918, 85961.0, 96644.51677741566, 96848.0, 97380.51309743406, 97780.51109744549, 8868.866966995629, 9012.0, 9019.368644194843, 9162.496062716615, 9227.215686666708, 56019.439805601425, 57098.714506427204, 57271.0, 58064.0, 58747.0, 68556.6572167136, 73703.6314818444, 74872.25127748653, 76093.23906760862, 84362.0, 13321.13412628176, 13926.373313200727, 14052.297385130907, 14165.866730661317, 14365.712685746017, 15642.765358519859, 16007.0, 16010.519684409486, 16277.511674649779, 16444.506664800076, 14692.191929443983, 14819.851801482278, 14917.0, 15169.469068582546, 15202.315895784937, 14697.191654459108, 14887.404503819726, 15072.472463463726, 15244.3902243909, 15543.37826486973, 11654.76690466169, 11696.0, 11731.23746956445, 14317.928410357881, 14815.33331000086, 11284.717882053013, 11682.88317116852, 11998.52042907547, 12116.454759535665, 12205.0, 12412.441440135035, 12450.25298482094, 12453.626391208278, 12725.363731813557, 12802.423890925116], "value": 7795.794090846885, "unit": "score"}], "name": "dromaeo_css", "value": 7281.429194943931, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 112.0, 111.0, 111.0, 112.0, 112.0, 111.0, 110.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 120.0, 120.0, 172.0, 118.0, 119.0, 150.0, 121.0, 152.0, 117.0], "value": 133.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 166.0, 160.0, 161.0, 159.0, 159.0, 161.0, 161.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 76.0, 104.0, 74.0, 76.0, 75.0, 75.0, 105.0, 74.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 125.0, 84.0, 84.0, 84.0, 119.0, 84.0, 85.0, 128.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 96.0, 94.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 49.0, 50.0, 51.0, 51.0, 50.0, 50.0, 56.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 60.0, 61.0, 61.0, 59.0, 60.0, 60.0, 60.0, 61.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 78.0, 76.0, 75.0, 74.0, 76.0, 75.0, 75.0, 75.0, 75.0], "value": 76.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 123.0, 121.0, 130.0, 126.0, 121.0, 127.0, 126.0, 141.0, 129.0], "value": 126.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 157.0, 155.0, 157.0, 156.0, 162.0, 155.0, 155.0, 156.0, 155.0], "value": 156.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 67.0, 64.0, 62.0, 64.0, 61.0, 62.0, 69.0, 61.0], "value": 63.9, "unit": "score"}], "name": "kraken", "value": 1472.2, "alertThreshold": 2.0}]} 12:27:56 INFO - Return code: 0 12:27:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:27:56 INFO - # TBPL SUCCESS # 12:27:56 INFO - Running post-action listener: _resource_record_post_action 12:27:56 INFO - [mozharness: 2016-08-09 19:27:56.840027Z] Finished run-tests step (success) 12:27:56 INFO - Running post-run listener: _resource_record_post_run 12:27:56 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 550158336; Read time: 12; Write time: 513764 12:27:56 INFO - TinderboxPrint: CPU usage
13.0% 12:27:56 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 12:27:56 INFO - TinderboxPrint: I/O write bytes / time
550,158,336 / 513,764 12:27:56 INFO - TinderboxPrint: CPU idle
8,289.4 (87.0%) 12:27:56 INFO - TinderboxPrint: CPU user
1,192.6 (12.5%) 12:27:56 INFO - TinderboxPrint: Swap in / out
0 / 0 12:27:56 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 258277376; Read time: 0; Write time: 290828 12:27:56 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 291258368; Read time: 0; Write time: 222788 12:27:57 INFO - Running post-run listener: _upload_blobber_files 12:27:57 INFO - Blob upload gear active. 12:27:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:27:57 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 12:27:57 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'] 12:27:57 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 12:27:57 INFO - (blobuploader) - INFO - Open directory for files ... 12:27:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:27:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:27:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:27:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:27:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:27:58 INFO - (blobuploader) - INFO - Done attempting. 12:27:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:27:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:27:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:28:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:01 INFO - (blobuploader) - INFO - Done attempting. 12:28:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:28:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:28:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:02 INFO - (blobuploader) - INFO - Done attempting. 12:28:02 INFO - (blobuploader) - INFO - Iteration through files over. 12:28:02 INFO - Return code: 0 12:28:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:28:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:28:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/310476b9db2354e5e8709375122efbf13e0cc191e493a975bd5b41087a0f529cf96f5274d63406480a6a8932b7b613f218defedd3159af6438b26aa4f3e669db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e2f6075913871a60edd476b764e7f86fbfbb71eb49c2811101a86014d7a316a86c9a95a111ff28c657be366fac5353837fb96240112eeb280cd4e6754eb83dd5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/58bc0348d1337ab91f49f2fe0d154480c2c72a173324c83cf08363a1296b29e349acd90a90260f8fc43fa7d9c615a5376c027609644daab546af3542ad5fa114"} 12:28:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:28:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:28:02 INFO - Contents: 12:28:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/310476b9db2354e5e8709375122efbf13e0cc191e493a975bd5b41087a0f529cf96f5274d63406480a6a8932b7b613f218defedd3159af6438b26aa4f3e669db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e2f6075913871a60edd476b764e7f86fbfbb71eb49c2811101a86014d7a316a86c9a95a111ff28c657be366fac5353837fb96240112eeb280cd4e6754eb83dd5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/58bc0348d1337ab91f49f2fe0d154480c2c72a173324c83cf08363a1296b29e349acd90a90260f8fc43fa7d9c615a5376c027609644daab546af3542ad5fa114"} 12:28:02 INFO - Running post-run listener: copy_logs_to_upload_dir 12:28:02 INFO - Copying logs to upload dir... 12:28:02 INFO - mkdir: /builds/slave/test/build/upload/logs 12:28:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.686770 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-08-09 12:28:02.335615) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 12:28:02.338780) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676 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/310476b9db2354e5e8709375122efbf13e0cc191e493a975bd5b41087a0f529cf96f5274d63406480a6a8932b7b613f218defedd3159af6438b26aa4f3e669db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e2f6075913871a60edd476b764e7f86fbfbb71eb49c2811101a86014d7a316a86c9a95a111ff28c657be366fac5353837fb96240112eeb280cd4e6754eb83dd5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/58bc0348d1337ab91f49f2fe0d154480c2c72a173324c83cf08363a1296b29e349acd90a90260f8fc43fa7d9c615a5376c027609644daab546af3542ad5fa114"} build_url:https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011530 build_url: 'https://queue.taskcluster.net/v1/task/Y3hWTioAQ9mvtBNdRdmuKA/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/310476b9db2354e5e8709375122efbf13e0cc191e493a975bd5b41087a0f529cf96f5274d63406480a6a8932b7b613f218defedd3159af6438b26aa4f3e669db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e2f6075913871a60edd476b764e7f86fbfbb71eb49c2811101a86014d7a316a86c9a95a111ff28c657be366fac5353837fb96240112eeb280cd4e6754eb83dd5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/58bc0348d1337ab91f49f2fe0d154480c2c72a173324c83cf08363a1296b29e349acd90a90260f8fc43fa7d9c615a5376c027609644daab546af3542ad5fa114"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 12:28:02.367869) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 12:28:02.368544) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1470764272.275074-1380806676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 12:28:02.452124) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 12:28:02.452869) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 12:28:02.453710) ========= ========= Total master_lag: 0.18 =========