builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-067 starttime: 1468973276.79 results: success (0) buildid: 20160719163419 builduid: 0ab8feb161124daf980a6663ac19a9c0 revision: abd1161f6a90962bc80c44b3f1d2f31392a118c6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 17:07:56.787463) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 17:07:56.788125) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 17:07:56.788457) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 17:07:56.803376) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 17:07:56.803668) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-19 17:07:57-- 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% 46.3M=0s 2016-07-19 17:07:57 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.074785 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 17:07:56.892764) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 17:07:56.893125) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016423 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 17:07:56.942100) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 17:07:56.942452) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev abd1161f6a90962bc80c44b3f1d2f31392a118c6 --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 abd1161f6a90962bc80c44b3f1d2f31392a118c6 --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=e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-19 17:07:57,490 truncating revision to first 12 chars 2016-07-19 17:07:57,491 Setting DEBUG logging. 2016-07-19 17:07:57,491 attempt 1/10 2016-07-19 17:07:57,491 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/abd1161f6a90?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 17:07:58,607 unpacking tar archive at: fx-team-abd1161f6a90/testing/mozharness/ program finished with exit code 0 elapsedTime=1.458161 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 17:07:58.414520) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 17:07:58.414878) ========= script_repo_revision: abd1161f6a90962bc80c44b3f1d2f31392a118c6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 17:07:58.415316) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 17:07:58.415645) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 17:07:58.424268) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-07-19 17:07:58.424619) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:07:59 INFO - MultiFileLogger online at 20160719 17:07:59 in /builds/slave/test 17:07:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 17:07:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:07:59 INFO - {'append_to_log': False, 17:07:59 INFO - 'base_work_dir': '/builds/slave/test', 17:07:59 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 17:07:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:07:59 INFO - 'branch': 'Fx-Team-Non-PGO', 17:07:59 INFO - 'buildbot_json_path': 'buildprops.json', 17:07:59 INFO - 'config_files': ('talos/linux_config.py',), 17:07:59 INFO - 'default_actions': ('clobber', 17:07:59 INFO - 'read-buildbot-config', 17:07:59 INFO - 'download-and-extract', 17:07:59 INFO - 'populate-webroot', 17:07:59 INFO - 'create-virtualenv', 17:07:59 INFO - 'install', 17:07:59 INFO - 'run-tests'), 17:07:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:07:59 INFO - 'download_minidump_stackwalk': True, 17:07:59 INFO - 'download_symbols': 'ondemand', 17:07:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:07:59 INFO - 'tooltool.py': '/tools/tooltool.py', 17:07:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:07:59 INFO - '/tools/misc-python/virtualenv.py')}, 17:07:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:07:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:07:59 INFO - 'installer_path': 'installer.exe', 17:07:59 INFO - 'log_level': 'info', 17:07:59 INFO - 'log_name': 'talos', 17:07:59 INFO - 'log_to_console': True, 17:07:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:07:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:07:59 INFO - 'opt_config_files': (), 17:07:59 INFO - 'pip_index': False, 17:07:59 INFO - 'sps_profile': False, 17:07:59 INFO - 'sps_profile_interval': 0, 17:07:59 INFO - 'suite': 'dromaeojs', 17:07:59 INFO - 'system_bits': '32', 17:07:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:07:59 INFO - 'title': 'talos-linux64-ix-067', 17:07:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:07:59 INFO - 'use_talos_json': True, 17:07:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:07:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:07:59 INFO - 'work_dir': 'build'} 17:07:59 INFO - ##### 17:07:59 INFO - ##### Running clobber step. 17:07:59 INFO - ##### 17:07:59 INFO - Running pre-action listener: _resource_record_pre_action 17:07:59 INFO - Running main action method: clobber 17:07:59 INFO - rmtree: /builds/slave/test/build 17:07:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:07:59 INFO - Running post-action listener: _resource_record_post_action 17:07:59 INFO - ##### 17:07:59 INFO - ##### Finished clobber step (success) 17:07:59 INFO - ##### 17:07:59 INFO - ##### 17:07:59 INFO - ##### Running read-buildbot-config step. 17:07:59 INFO - ##### 17:07:59 INFO - Running pre-action listener: _resource_record_pre_action 17:07:59 INFO - Running main action method: read_buildbot_config 17:07:59 INFO - Using buildbot properties: 17:07:59 INFO - { 17:07:59 INFO - "project": "", 17:07:59 INFO - "product": "firefox", 17:07:59 INFO - "script_repo_revision": "production", 17:07:59 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 17:07:59 INFO - "repository": "", 17:07:59 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 17:07:59 INFO - "buildid": "20160719163419", 17:07:59 INFO - "pgo_build": "False", 17:07:59 INFO - "basedir": "/builds/slave/test", 17:07:59 INFO - "buildnumber": 3181, 17:07:59 INFO - "slavename": "talos-linux64-ix-067", 17:07:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:07:59 INFO - "platform": "ubuntu64_hw", 17:07:59 INFO - "scheduler": "tests-fx-team-linux64-talos", 17:07:59 INFO - "branch": "fx-team", 17:07:59 INFO - "repo_path": "integration/fx-team", 17:07:59 INFO - "revision": "abd1161f6a90962bc80c44b3f1d2f31392a118c6", 17:07:59 INFO - "stage_platform": "linux64", 17:07:59 INFO - "builduid": "0ab8feb161124daf980a6663ac19a9c0", 17:07:59 INFO - "slavebuilddir": "test" 17:07:59 INFO - } 17:07:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 17:07:59 INFO - Running post-action listener: _resource_record_post_action 17:07:59 INFO - ##### 17:07:59 INFO - ##### Finished read-buildbot-config step (success) 17:07:59 INFO - ##### 17:07:59 INFO - ##### 17:07:59 INFO - ##### Running download-and-extract step. 17:07:59 INFO - ##### 17:07:59 INFO - Running pre-action listener: _resource_record_pre_action 17:07:59 INFO - Running main action method: download_and_extract 17:07:59 INFO - mkdir: /builds/slave/test/build/tests 17:07:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:07:59 INFO - https://queue.taskcluster.net/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:07:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 17:07:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 17:07:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 17:07:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:08:00 INFO - Downloaded 1361 bytes. 17:08:00 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 17:08:00 INFO - Using the following test package requirements: 17:08:00 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 17:08:00 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:08:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:08:00 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:08:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:08:00 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:08:00 INFO - u'jsshell-linux-x86_64.zip'], 17:08:00 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:08:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:08:00 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 17:08:00 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:08:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:08:00 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:08:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:08:00 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:08:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:08:00 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 17:08:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:08:00 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:00 INFO - https://queue.taskcluster.net/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:08:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 17:08:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 17:08:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 17:08:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:08:01 INFO - Downloaded 22763363 bytes. 17:08:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:08:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:08:03 INFO - Return code: 0 17:08:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:03 INFO - https://queue.taskcluster.net/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:08:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 17:08:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 17:08:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 17:08:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:08:04 INFO - Downloaded 22763363 bytes. 17:08:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:08:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:08:05 INFO - Return code: 0 17:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:05 INFO - https://queue.taskcluster.net/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:08:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 17:08:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 17:08:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 17:08:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:08:06 INFO - Downloaded 14403317 bytes. 17:08:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:08:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:08:07 INFO - Return code: 0 17:08:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:07 INFO - https://queue.taskcluster.net/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:08:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 17:08:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 17:08:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 17:08:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:08:08 INFO - Downloaded 57125508 bytes. 17:08:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 17:08:08 INFO - mkdir: /builds/slave/test/properties 17:08:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:08:08 INFO - Writing to file /builds/slave/test/properties/build_url 17:08:08 INFO - Contents: 17:08:08 INFO - build_url:https://queue.taskcluster.net/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 17:08:09 INFO - Running post-action listener: _resource_record_post_action 17:08:09 INFO - Running post-action listener: set_extra_try_arguments 17:08:09 INFO - ##### 17:08:09 INFO - ##### Finished download-and-extract step (success) 17:08:09 INFO - ##### 17:08:09 INFO - ##### 17:08:09 INFO - ##### Running populate-webroot step. 17:08:09 INFO - ##### 17:08:09 INFO - Running pre-action listener: _resource_record_pre_action 17:08:09 INFO - Running main action method: populate_webroot 17:08:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:08:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:08:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:08:09 INFO - '--tppagecycles', 17:08:09 INFO - '7'], 17:08:09 INFO - 'tests': ['tsvgm']}}, 17:08:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:08:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:08:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:08:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:08:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:08:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:08:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:09 INFO - 'talos_options': ['--disable-e10s'], 17:08:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:08:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:08:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:09 INFO - 'talos_options': ['--disable-e10s'], 17:08:09 INFO - 'tests': ['damp', 'tps']}, 17:08:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:09 INFO - 'tests': ['damp', 'tps']}, 17:08:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:08:09 INFO - 'tests': ['dromaeo_dom']}, 17:08:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:08:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:08:09 INFO - 'tests': ['basic_compositor_video']}, 17:08:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:08:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:08:09 INFO - 'tests': ['a11yr', 17:08:09 INFO - 'ts_paint', 17:08:09 INFO - 'tpaint', 17:08:09 INFO - 'sessionrestore', 17:08:09 INFO - 'sessionrestore_no_auto_restore', 17:08:09 INFO - 'tabpaint']}, 17:08:09 INFO - 'other-e10s': {'tests': ['a11yr', 17:08:09 INFO - 'ts_paint', 17:08:09 INFO - 'tpaint', 17:08:09 INFO - 'sessionrestore', 17:08:09 INFO - 'sessionrestore_no_auto_restore', 17:08:09 INFO - 'tabpaint']}, 17:08:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:08:09 INFO - 'ts_paint', 17:08:09 INFO - 'tpaint', 17:08:09 INFO - 'sessionrestore', 17:08:09 INFO - 'sessionrestore_no_auto_restore', 17:08:09 INFO - 'tabpaint']}, 17:08:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:08:09 INFO - 'ts_paint', 17:08:09 INFO - 'tpaint', 17:08:09 INFO - 'sessionrestore', 17:08:09 INFO - 'sessionrestore_no_auto_restore', 17:08:09 INFO - 'tabpaint']}, 17:08:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:08:09 INFO - 'tests': ['a11yr', 17:08:09 INFO - 'ts_paint', 17:08:09 INFO - 'tpaint', 17:08:09 INFO - 'sessionrestore', 17:08:09 INFO - 'sessionrestore_no_auto_restore', 17:08:09 INFO - 'tabpaint']}, 17:08:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:08:09 INFO - 'tests': ['a11yr', 17:08:09 INFO - 'ts_paint', 17:08:09 INFO - 'tpaint', 17:08:09 INFO - 'sessionrestore', 17:08:09 INFO - 'sessionrestore_no_auto_restore', 17:08:09 INFO - 'tabpaint']}, 17:08:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:08:09 INFO - 'tests': ['tsvgx', 17:08:09 INFO - 'tsvgr_opacity', 17:08:09 INFO - 'tart', 17:08:09 INFO - 'tscrollx', 17:08:09 INFO - 'cart']}, 17:08:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:08:09 INFO - 'tsvgr_opacity', 17:08:09 INFO - 'tart', 17:08:09 INFO - 'tscrollx', 17:08:09 INFO - 'cart']}, 17:08:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:09 INFO - 'talos_options': ['--disable-e10s'], 17:08:09 INFO - 'tests': ['tp5o']}, 17:08:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:09 INFO - 'tests': ['tp5o']}, 17:08:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:09 INFO - 'talos_options': ['--disable-e10s', 17:08:09 INFO - '--xperf_path', 17:08:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:08:09 INFO - 'tests': ['tp5n']}, 17:08:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:09 INFO - 'talos_options': ['--xperf_path', 17:08:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:08:09 INFO - 'tests': ['tp5n']}}, 17:08:09 INFO - 'talos.zip': {'path': '', 17:08:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:08:09 INFO - Running post-action listener: _resource_record_post_action 17:08:09 INFO - ##### 17:08:09 INFO - ##### Finished populate-webroot step (success) 17:08:09 INFO - ##### 17:08:09 INFO - ##### 17:08:09 INFO - ##### Running create-virtualenv step. 17:08:09 INFO - ##### 17:08:09 INFO - Running pre-action listener: _resource_record_pre_action 17:08:09 INFO - Running main action method: create_virtualenv 17:08:09 INFO - Creating virtualenv /builds/slave/test/build/venv 17:08:09 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 17:08:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:08:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:08:09 INFO - Using real prefix '/usr' 17:08:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:08:09 INFO - Installing distribute.............................................................................................................................................................................................done. 17:08:11 INFO - Installing pip.................done. 17:08:11 INFO - Return code: 0 17:08:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:08:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:08:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:08:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b3b0>, '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 0x293c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', '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 17:08:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:08:11 INFO - Using env: {'DISPLAY': ':0', 17:08:11 INFO - 'HOME': '/home/cltbld', 17:08:11 INFO - 'LANG': 'en_US.UTF-8', 17:08:11 INFO - 'LANGUAGE': 'en_US:en', 17:08:11 INFO - 'LOGNAME': 'cltbld', 17:08:11 INFO - 'MAIL': '/var/mail/cltbld', 17:08:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:11 INFO - 'MOZ_NO_REMOTE': '1', 17:08:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:08:11 INFO - 'NO_EM_RESTART': '1', 17:08:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:08:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:11 INFO - 'PWD': '/builds/slave/test', 17:08:11 INFO - 'SHELL': '/bin/bash', 17:08:11 INFO - 'SHLVL': '1', 17:08:11 INFO - 'TERM': 'linux', 17:08:11 INFO - 'TMOUT': '86400', 17:08:11 INFO - 'USER': 'cltbld', 17:08:11 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', 17:08:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:11 INFO - '_': '/tools/buildbot/bin/python'} 17:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:08:12 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 17:08:12 INFO - Cleaning up... 17:08:12 INFO - Return code: 0 17:08:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:08:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:08:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:12 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 0x267c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b3b0>, '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 0x293c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', '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 17:08:12 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 17:08:12 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 17:08:12 INFO - Using env: {'DISPLAY': ':0', 17:08:12 INFO - 'HOME': '/home/cltbld', 17:08:12 INFO - 'LANG': 'en_US.UTF-8', 17:08:12 INFO - 'LANGUAGE': 'en_US:en', 17:08:12 INFO - 'LOGNAME': 'cltbld', 17:08:12 INFO - 'MAIL': '/var/mail/cltbld', 17:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:12 INFO - 'MOZ_NO_REMOTE': '1', 17:08:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:08:12 INFO - 'NO_EM_RESTART': '1', 17:08:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:12 INFO - 'PWD': '/builds/slave/test', 17:08:12 INFO - 'SHELL': '/bin/bash', 17:08:12 INFO - 'SHLVL': '1', 17:08:12 INFO - 'TERM': 'linux', 17:08:12 INFO - 'TMOUT': '86400', 17:08:12 INFO - 'USER': 'cltbld', 17:08:12 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', 17:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:12 INFO - '_': '/tools/buildbot/bin/python'} 17:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:08:12 INFO - Downloading/unpacking psutil>=3.1.1 17:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:08:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:08:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:08:14 INFO - Installing collected packages: psutil 17:08:14 INFO - Running setup.py install for psutil 17:08:14 INFO - building 'psutil._psutil_linux' extension 17:08:14 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 17:08:14 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 17:08:14 INFO - building 'psutil._psutil_posix' extension 17:08:14 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 17:08:14 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 17:08:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:08:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:08:14 INFO - Successfully installed psutil 17:08:14 INFO - Cleaning up... 17:08:14 INFO - Return code: 0 17:08:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:08:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:08:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:08:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b3b0>, '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 0x293c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', '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 17:08:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:08:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:08:14 INFO - Using env: {'DISPLAY': ':0', 17:08:14 INFO - 'HOME': '/home/cltbld', 17:08:14 INFO - 'LANG': 'en_US.UTF-8', 17:08:14 INFO - 'LANGUAGE': 'en_US:en', 17:08:14 INFO - 'LOGNAME': 'cltbld', 17:08:14 INFO - 'MAIL': '/var/mail/cltbld', 17:08:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:14 INFO - 'MOZ_NO_REMOTE': '1', 17:08:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:08:14 INFO - 'NO_EM_RESTART': '1', 17:08:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:08:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:14 INFO - 'PWD': '/builds/slave/test', 17:08:14 INFO - 'SHELL': '/bin/bash', 17:08:14 INFO - 'SHLVL': '1', 17:08:14 INFO - 'TERM': 'linux', 17:08:14 INFO - 'TMOUT': '86400', 17:08:14 INFO - 'USER': 'cltbld', 17:08:14 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', 17:08:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:14 INFO - '_': '/tools/buildbot/bin/python'} 17:08:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:08:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:08:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:08:17 INFO - Installing collected packages: mozsystemmonitor 17:08:17 INFO - Running setup.py install for mozsystemmonitor 17:08:17 INFO - Successfully installed mozsystemmonitor 17:08:17 INFO - Cleaning up... 17:08:17 INFO - Return code: 0 17:08:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:17 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 0x267c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b3b0>, '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 0x293c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', '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 17:08:17 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 17:08:17 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 17:08:17 INFO - Using env: {'DISPLAY': ':0', 17:08:17 INFO - 'HOME': '/home/cltbld', 17:08:17 INFO - 'LANG': 'en_US.UTF-8', 17:08:17 INFO - 'LANGUAGE': 'en_US:en', 17:08:17 INFO - 'LOGNAME': 'cltbld', 17:08:17 INFO - 'MAIL': '/var/mail/cltbld', 17:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:17 INFO - 'MOZ_NO_REMOTE': '1', 17:08:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:08:17 INFO - 'NO_EM_RESTART': '1', 17:08:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:17 INFO - 'PWD': '/builds/slave/test', 17:08:17 INFO - 'SHELL': '/bin/bash', 17:08:17 INFO - 'SHLVL': '1', 17:08:17 INFO - 'TERM': 'linux', 17:08:17 INFO - 'TMOUT': '86400', 17:08:17 INFO - 'USER': 'cltbld', 17:08:17 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', 17:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:17 INFO - '_': '/tools/buildbot/bin/python'} 17:08:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:08:17 INFO - Downloading/unpacking jsonschema==2.5.1 17:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:08:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:08:19 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 17:08:19 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 17:08:19 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 17:08:19 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 17:08:19 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 17:08:19 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 17:08:19 INFO - Downloading functools32-3.2.3-2.tar.gz 17:08:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:08:19 INFO - warning: no files found matching '*.txt' 17:08:19 INFO - no previously-included directories found matching 'build' 17:08:19 INFO - no previously-included directories found matching 'dist' 17:08:19 INFO - no previously-included directories found matching '.git*' 17:08:19 INFO - Installing collected packages: jsonschema, functools32 17:08:19 INFO - Running setup.py install for functools32 17:08:19 INFO - warning: no files found matching '*.txt' 17:08:19 INFO - no previously-included directories found matching 'build' 17:08:19 INFO - no previously-included directories found matching 'dist' 17:08:19 INFO - no previously-included directories found matching '.git*' 17:08:19 INFO - Successfully installed jsonschema functools32 17:08:19 INFO - Cleaning up... 17:08:19 INFO - Return code: 0 17:08:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:08:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:08:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:19 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 0x267c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b3b0>, '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 0x293c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', '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 17:08:19 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 17:08:19 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 17:08:19 INFO - Using env: {'DISPLAY': ':0', 17:08:19 INFO - 'HOME': '/home/cltbld', 17:08:19 INFO - 'LANG': 'en_US.UTF-8', 17:08:19 INFO - 'LANGUAGE': 'en_US:en', 17:08:19 INFO - 'LOGNAME': 'cltbld', 17:08:19 INFO - 'MAIL': '/var/mail/cltbld', 17:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:19 INFO - 'MOZ_NO_REMOTE': '1', 17:08:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:08:19 INFO - 'NO_EM_RESTART': '1', 17:08:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:19 INFO - 'PWD': '/builds/slave/test', 17:08:19 INFO - 'SHELL': '/bin/bash', 17:08:19 INFO - 'SHLVL': '1', 17:08:19 INFO - 'TERM': 'linux', 17:08:19 INFO - 'TMOUT': '86400', 17:08:19 INFO - 'USER': 'cltbld', 17:08:19 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', 17:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:19 INFO - '_': '/tools/buildbot/bin/python'} 17:08:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:08:20 INFO - Downloading/unpacking blobuploader==1.2.4 17:08:20 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 17:08:20 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 17:08:20 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 17:08:20 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 17:08:20 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 17:08:20 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 17:08:21 INFO - Downloading blobuploader-1.2.4.tar.gz 17:08:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:08:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:08:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:22 INFO - Downloading docopt-0.6.1.tar.gz 17:08:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:08:22 INFO - Installing collected packages: blobuploader, requests, docopt 17:08:22 INFO - Running setup.py install for blobuploader 17:08:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:08:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:08:22 INFO - Running setup.py install for requests 17:08:23 INFO - Running setup.py install for docopt 17:08:23 INFO - Successfully installed blobuploader requests docopt 17:08:23 INFO - Cleaning up... 17:08:23 INFO - Return code: 0 17:08:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:08:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:08:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:23 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 0x267c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b3b0>, '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 0x293c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', '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 17:08:23 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 17:08:23 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 17:08:23 INFO - Using env: {'DISPLAY': ':0', 17:08:23 INFO - 'HOME': '/home/cltbld', 17:08:23 INFO - 'LANG': 'en_US.UTF-8', 17:08:23 INFO - 'LANGUAGE': 'en_US:en', 17:08:23 INFO - 'LOGNAME': 'cltbld', 17:08:23 INFO - 'MAIL': '/var/mail/cltbld', 17:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:23 INFO - 'MOZ_NO_REMOTE': '1', 17:08:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:08:23 INFO - 'NO_EM_RESTART': '1', 17:08:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:23 INFO - 'PWD': '/builds/slave/test', 17:08:23 INFO - 'SHELL': '/bin/bash', 17:08:23 INFO - 'SHLVL': '1', 17:08:23 INFO - 'TERM': 'linux', 17:08:23 INFO - 'TMOUT': '86400', 17:08:23 INFO - 'USER': 'cltbld', 17:08:23 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', 17:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:23 INFO - '_': '/tools/buildbot/bin/python'} 17:08:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:08:23 INFO - Running setup.py (path:/tmp/pip-1MY7Q3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:08:23 INFO - Running setup.py (path:/tmp/pip-kJGadV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:08:23 INFO - Running setup.py (path:/tmp/pip-qsMFXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:08:23 INFO - Running setup.py (path:/tmp/pip-V5kCSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:08:23 INFO - Running setup.py (path:/tmp/pip-dNJ8x6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:08:23 INFO - Running setup.py (path:/tmp/pip-rileN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:08:23 INFO - Running setup.py (path:/tmp/pip-a5hsTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:08:24 INFO - Running setup.py (path:/tmp/pip-S9naCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:08:24 INFO - Running setup.py (path:/tmp/pip-SJ7Qqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:08:24 INFO - Running setup.py (path:/tmp/pip-_0SqHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:08:24 INFO - Running setup.py (path:/tmp/pip-An3Ar4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:08:24 INFO - Running setup.py (path:/tmp/pip-4H9bcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:08:24 INFO - Running setup.py (path:/tmp/pip-fTL_DN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:08:24 INFO - Running setup.py (path:/tmp/pip-vT07U_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:08:24 INFO - Running setup.py (path:/tmp/pip-U8UIYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:08:24 INFO - Running setup.py (path:/tmp/pip-WkUnzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:08:24 INFO - Running setup.py (path:/tmp/pip-WLSBSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:08:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:08:24 INFO - Running setup.py install for manifestparser 17:08:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:08:24 INFO - Running setup.py install for mozcrash 17:08:24 INFO - Running setup.py install for mozdebug 17:08:25 INFO - Running setup.py install for mozdevice 17:08:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:08:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:08:25 INFO - Running setup.py install for mozfile 17:08:25 INFO - Running setup.py install for mozhttpd 17:08:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:08:25 INFO - Running setup.py install for mozinfo 17:08:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:08:25 INFO - Running setup.py install for mozInstall 17:08:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:08:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:08:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:08:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:08:25 INFO - Running setup.py install for mozleak 17:08:25 INFO - Running setup.py install for mozlog 17:08:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:08:25 INFO - Running setup.py install for moznetwork 17:08:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:08:25 INFO - Running setup.py install for mozprocess 17:08:25 INFO - Running setup.py install for mozprofile 17:08:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:08:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:08:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:08:26 INFO - Running setup.py install for mozrunner 17:08:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:08:26 INFO - Running setup.py install for mozscreenshot 17:08:26 INFO - Running setup.py install for moztest 17:08:26 INFO - Running setup.py install for mozversion 17:08:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:08:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:08:26 INFO - Cleaning up... 17:08:26 INFO - Return code: 0 17:08:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:08:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:08:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:26 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 0x267c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b3b0>, '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 0x293c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', '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 17:08:26 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 17:08:26 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 17:08:26 INFO - Using env: {'DISPLAY': ':0', 17:08:26 INFO - 'HOME': '/home/cltbld', 17:08:26 INFO - 'LANG': 'en_US.UTF-8', 17:08:26 INFO - 'LANGUAGE': 'en_US:en', 17:08:26 INFO - 'LOGNAME': 'cltbld', 17:08:26 INFO - 'MAIL': '/var/mail/cltbld', 17:08:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:26 INFO - 'MOZ_NO_REMOTE': '1', 17:08:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:08:26 INFO - 'NO_EM_RESTART': '1', 17:08:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:08:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:26 INFO - 'PWD': '/builds/slave/test', 17:08:26 INFO - 'SHELL': '/bin/bash', 17:08:26 INFO - 'SHLVL': '1', 17:08:26 INFO - 'TERM': 'linux', 17:08:26 INFO - 'TMOUT': '86400', 17:08:26 INFO - 'USER': 'cltbld', 17:08:26 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', 17:08:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:26 INFO - '_': '/tools/buildbot/bin/python'} 17:08:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:08:26 INFO - Running setup.py (path:/tmp/pip-cuZhHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:08:26 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)) 17:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:08:26 INFO - Running setup.py (path:/tmp/pip-Huji7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:08:26 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)) 17:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:08:26 INFO - Running setup.py (path:/tmp/pip-hRhzf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:08:26 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)) 17:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:08:26 INFO - Running setup.py (path:/tmp/pip-0LPD9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:08:27 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)) 17:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:08:27 INFO - Running setup.py (path:/tmp/pip-WFJfdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:08:27 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)) 17:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:08:27 INFO - Running setup.py (path:/tmp/pip-M6WIgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:08:27 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)) 17:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:08:27 INFO - Running setup.py (path:/tmp/pip-C3oWI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:08:27 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)) 17:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:08:27 INFO - Running setup.py (path:/tmp/pip-ULzFI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:08:27 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)) 17:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:08:27 INFO - Running setup.py (path:/tmp/pip-KGxMKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:08:27 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)) 17:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:08:27 INFO - Running setup.py (path:/tmp/pip-8By01J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:08:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:08:27 INFO - Running setup.py (path:/tmp/pip-1jSeIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:08:27 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)) 17:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:08:27 INFO - Running setup.py (path:/tmp/pip-ixqZXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:08:27 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)) 17:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:08:27 INFO - Running setup.py (path:/tmp/pip-jaocRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:08:27 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)) 17:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:08:27 INFO - Running setup.py (path:/tmp/pip-iiI_7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:08:27 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)) 17:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:08:27 INFO - Running setup.py (path:/tmp/pip-voGzmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:08:27 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)) 17:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:08:27 INFO - Running setup.py (path:/tmp/pip-Im_vUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:08:27 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)) 17:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:08:27 INFO - Running setup.py (path:/tmp/pip-20mERz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:08:27 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)) 17:08:27 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)) 17:08:27 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)) 17:08:27 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)) 17:08:27 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)) 17:08:27 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)) 17:08:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:08:27 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 17:08:27 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 17:08:27 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 17:08:27 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 17:08:27 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 17:08:27 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 17:08:29 INFO - Downloading blessings-1.6.tar.gz 17:08:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:08:29 INFO - Installing collected packages: blessings 17:08:29 INFO - Running setup.py install for blessings 17:08:30 INFO - Successfully installed blessings 17:08:30 INFO - Cleaning up... 17:08:30 INFO - Return code: 0 17:08:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:08:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:08:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:08:30 INFO - Reading from file tmpfile_stdout 17:08:30 INFO - Current package versions: 17:08:30 INFO - argparse == 1.2.1 17:08:30 INFO - blessings == 1.6 17:08:30 INFO - blobuploader == 1.2.4 17:08:30 INFO - docopt == 0.6.1 17:08:30 INFO - functools32 == 3.2.3-2 17:08:30 INFO - jsonschema == 2.5.1 17:08:30 INFO - manifestparser == 1.1 17:08:30 INFO - mozInstall == 1.12 17:08:30 INFO - mozcrash == 0.17 17:08:30 INFO - mozdebug == 0.1 17:08:30 INFO - mozdevice == 0.48 17:08:30 INFO - mozfile == 1.2 17:08:30 INFO - mozhttpd == 0.7 17:08:30 INFO - mozinfo == 0.9 17:08:30 INFO - mozleak == 0.1 17:08:30 INFO - mozlog == 3.2 17:08:30 INFO - moznetwork == 0.27 17:08:30 INFO - mozprocess == 0.23 17:08:30 INFO - mozprofile == 0.28 17:08:30 INFO - mozrunner == 6.12 17:08:30 INFO - mozscreenshot == 0.1 17:08:30 INFO - mozsystemmonitor == 0.3 17:08:30 INFO - moztest == 0.7 17:08:30 INFO - mozversion == 1.4 17:08:30 INFO - psutil == 3.1.1 17:08:30 INFO - requests == 1.2.3 17:08:30 INFO - wsgiref == 0.1.2 17:08:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:08:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:08:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:08:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:30 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 0x267c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b3b0>, '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 0x293c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', '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 17:08:30 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 17:08:30 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 17:08:30 INFO - Using env: {'DISPLAY': ':0', 17:08:30 INFO - 'HOME': '/home/cltbld', 17:08:30 INFO - 'LANG': 'en_US.UTF-8', 17:08:30 INFO - 'LANGUAGE': 'en_US:en', 17:08:30 INFO - 'LOGNAME': 'cltbld', 17:08:30 INFO - 'MAIL': '/var/mail/cltbld', 17:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:30 INFO - 'MOZ_NO_REMOTE': '1', 17:08:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:08:30 INFO - 'NO_EM_RESTART': '1', 17:08:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:30 INFO - 'PWD': '/builds/slave/test', 17:08:30 INFO - 'SHELL': '/bin/bash', 17:08:30 INFO - 'SHLVL': '1', 17:08:30 INFO - 'TERM': 'linux', 17:08:30 INFO - 'TMOUT': '86400', 17:08:30 INFO - 'USER': 'cltbld', 17:08:30 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', 17:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:30 INFO - '_': '/tools/buildbot/bin/python'} 17:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:08:30 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)) 17:08:30 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)) 17:08:30 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)) 17:08:30 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)) 17:08:30 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)) 17:08:30 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)) 17:08:30 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)) 17:08:30 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)) 17:08:30 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)) 17:08:30 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)) 17:08:30 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)) 17:08:30 INFO - Cleaning up... 17:08:30 INFO - Return code: 0 17:08:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:08:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:08:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:08:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:30 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 0x267c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x293d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b3b0>, '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 0x293c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', '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 17:08:30 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 17:08:30 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 17:08:30 INFO - Using env: {'DISPLAY': ':0', 17:08:30 INFO - 'HOME': '/home/cltbld', 17:08:30 INFO - 'LANG': 'en_US.UTF-8', 17:08:30 INFO - 'LANGUAGE': 'en_US:en', 17:08:30 INFO - 'LOGNAME': 'cltbld', 17:08:30 INFO - 'MAIL': '/var/mail/cltbld', 17:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:30 INFO - 'MOZ_NO_REMOTE': '1', 17:08:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:08:30 INFO - 'NO_EM_RESTART': '1', 17:08:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:30 INFO - 'PWD': '/builds/slave/test', 17:08:30 INFO - 'SHELL': '/bin/bash', 17:08:30 INFO - 'SHLVL': '1', 17:08:30 INFO - 'TERM': 'linux', 17:08:30 INFO - 'TMOUT': '86400', 17:08:30 INFO - 'USER': 'cltbld', 17:08:30 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', 17:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:30 INFO - '_': '/tools/buildbot/bin/python'} 17:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:08:30 INFO - Cleaning up... 17:08:30 INFO - Return code: 0 17:08:30 INFO - Running post-action listener: _resource_record_post_action 17:08:30 INFO - Running post-action listener: _start_resource_monitoring 17:08:30 INFO - Starting resource monitoring. 17:08:30 INFO - ##### 17:08:30 INFO - ##### Finished create-virtualenv step (success) 17:08:30 INFO - ##### 17:08:30 INFO - ##### 17:08:30 INFO - ##### Running install step. 17:08:30 INFO - ##### 17:08:30 INFO - Running pre-action listener: _resource_record_pre_action 17:08:30 INFO - Running main action method: install 17:08:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:08:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:08:31 INFO - Reading from file tmpfile_stdout 17:08:31 INFO - Detecting whether we're running mozinstall >=1.0... 17:08:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:08:31 INFO - Reading from file tmpfile_stdout 17:08:31 INFO - Output received: 17:08:31 INFO - Usage: mozinstall [options] installer 17:08:31 INFO - Options: 17:08:31 INFO - -h, --help show this help message and exit 17:08:31 INFO - -d DEST, --destination=DEST 17:08:31 INFO - Directory to install application into. [default: 17:08:31 INFO - "/builds/slave/test"] 17:08:31 INFO - --app=APP Application being installed. [default: firefox] 17:08:31 INFO - mkdir: /builds/slave/test/build/application 17:08:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:08:47 INFO - Reading from file tmpfile_stdout 17:08:47 INFO - Output received: 17:08:47 INFO - /builds/slave/test/build/application/firefox/firefox 17:08:47 INFO - Running post-action listener: _resource_record_post_action 17:08:47 INFO - ##### 17:08:47 INFO - ##### Finished install step (success) 17:08:47 INFO - ##### 17:08:47 INFO - ##### 17:08:47 INFO - ##### Running run-tests step. 17:08:47 INFO - ##### 17:08:47 INFO - Running pre-action listener: _resource_record_pre_action 17:08:47 INFO - Running main action method: run_tests 17:08:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:08:47 INFO - Python 2.7.3 17:08:47 INFO - Return code: 0 17:08:47 INFO - grabbing minidump binary from tooltool 17:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:47 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 0x293b3b0>, '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 0x293c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:08:47 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 17:08:47 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 17:08:47 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 17:08:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:08:47 INFO - Return code: 0 17:08:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:08:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:08:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:08:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:08:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:08:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 17:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 17:08:47 INFO - Using env: {'DISPLAY': ':0', 17:08:47 INFO - 'HOME': '/home/cltbld', 17:08:47 INFO - 'LANG': 'en_US.UTF-8', 17:08:47 INFO - 'LANGUAGE': 'en_US:en', 17:08:47 INFO - 'LOGNAME': 'cltbld', 17:08:47 INFO - 'MAIL': '/var/mail/cltbld', 17:08:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:08:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:47 INFO - 'MOZ_NO_REMOTE': '1', 17:08:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:08:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:08:47 INFO - 'NO_EM_RESTART': '1', 17:08:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:47 INFO - 'PWD': '/builds/slave/test', 17:08:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:08:47 INFO - 'SHELL': '/bin/bash', 17:08:47 INFO - 'SHLVL': '1', 17:08:47 INFO - 'TERM': 'linux', 17:08:47 INFO - 'TMOUT': '86400', 17:08:47 INFO - 'USER': 'cltbld', 17:08:47 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808', 17:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:47 INFO - '_': '/tools/buildbot/bin/python'} 17:08:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 17:08:47 INFO - mozversion application_buildid: 20160719163419 17:08:47 INFO - mozversion application_changeset: abd1161f6a90962bc80c44b3f1d2f31392a118c6 17:08:47 INFO - mozversion application_display_name: Nightly 17:08:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:08:47 INFO - mozversion application_name: Firefox 17:08:47 INFO - mozversion application_remotingname: firefox 17:08:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:08:47 INFO - mozversion application_vendor: Mozilla 17:08:47 INFO - mozversion application_version: 50.0a1 17:08:47 INFO - mozversion platform_buildid: 20160719163419 17:08:47 INFO - mozversion platform_changeset: abd1161f6a90962bc80c44b3f1d2f31392a118c6 17:08:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:08:47 INFO - mozversion platform_version: 50.0a1 17:08:47 INFO - using testdate: 1468973327 17:08:47 INFO - actual date: 1468973327 17:08:47 INFO - starting webserver on 'localhost:41665' 17:08:47 INFO - SUITE-START | Running 2 tests 17:08:47 INFO - TEST-START | dromaeo_css 17:08:47 INFO - operating with platform_type : linux_ 17:08:47 INFO - Initialising browser for dromaeo_css test... 17:08:47 INFO - TEST-INFO | started process 31212 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSsDuA1/profile http://localhost:41665/getInfo.html) 17:08:51 INFO - PROCESS | 31212 | __metrics Screen width/height:1600/1200 17:08:51 INFO - PROCESS | 31212 | colorDepth:24 17:08:51 INFO - PROCESS | 31212 | Browser inner width/height: 1024/697 17:08:51 INFO - PROCESS | 31212 | __metrics 17:08:52 INFO - PROCESS | 31212 | 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" 17:08:52 INFO - PROCESS | 31212 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:08:53 INFO - TEST-INFO | 31212: exit 0 17:08:53 INFO - Browser initialized. 17:08:53 INFO - Running cycle 1/1 for dromaeo_css test... 17:08:53 INFO - TEST-INFO | started process 31293 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSsDuA1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:09:05 INFO - PROCESS | 31293 | RSS: Main: 159232000 17:09:05 INFO - PROCESS | 31293 | 17:09:56 INFO - PROCESS | 31293 | 1468973396118 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] 17:12:08 INFO - PROCESS | 31293 | Cycle 1(1): loaded http://localhost:41665/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41665/tests/dromaeo/cssquery-ext.html) 17:12:10 INFO - PROCESS | 31293 | RSS: Main: 265904128 17:12:10 INFO - PROCESS | 31293 | 17:15:12 INFO - PROCESS | 31293 | Cycle 1(1): loaded http://localhost:41665/tests/dromaeo/cssquery-ext.html (next: http://localhost:41665/tests/dromaeo/cssquery-jquery.html) 17:15:13 INFO - PROCESS | 31293 | RSS: Main: 240947200 17:15:13 INFO - PROCESS | 31293 | 17:18:15 INFO - PROCESS | 31293 | Cycle 1(1): loaded http://localhost:41665/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41665/tests/dromaeo/cssquery-mootools.html) 17:18:16 INFO - PROCESS | 31293 | RSS: Main: 241631232 17:18:16 INFO - PROCESS | 31293 | 17:21:18 INFO - PROCESS | 31293 | Cycle 1(1): loaded http://localhost:41665/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41665/tests/dromaeo/cssquery-prototype.html) 17:21:18 INFO - PROCESS | 31293 | RSS: Main: 245215232 17:21:18 INFO - PROCESS | 31293 | 17:24:21 INFO - PROCESS | 31293 | Cycle 1(1): loaded http://localhost:41665/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41665/tests/dromaeo/cssquery-yui.html) 17:24:22 INFO - PROCESS | 31293 | RSS: Main: 212815872 17:24:22 INFO - PROCESS | 31293 | 17:27:25 INFO - PROCESS | 31293 | Cycle 1(1): loaded http://localhost:41665/tests/dromaeo/cssquery-yui.html (next: http://localhost:41665/tests/dromaeo/cssquery-dojo.html) 17:27:25 INFO - PROCESS | 31293 | RSS: Main: 278159360 17:27:25 INFO - PROCESS | 31293 | 17:27:25 INFO - PROCESS | 31293 | __start_tp_report 17:27:25 INFO - PROCESS | 31293 | _x_x_mozilla_page_load 17:27:25 INFO - PROCESS | 31293 | _x_x_mozilla_page_load_details 17:27:25 INFO - PROCESS | 31293 | |i|pagename|runs| 17:27:25 INFO - PROCESS | 31293 | |0;dojo.html;2619.476104779044;2633.0257804612297;2633.3285012321853;2634.723354047825;2635.407033417481;4556.886077848056;4635.026644404671;4707.529247075291;4737.242041273393;4744.264638980955;4646.3495110684535;4686.835960741366;4687.921777991064;4715.41057367829;4720.103180395731;4111.218868415007;4278.25130602145;4359.3025115981445;4388.736675799446;4442.3336499525;4353.825846966118;4383.035732138925;4398.142362239365;4405.471343438792;4431.423914891076;4243.936340954888;4280.015596412843;4309.310510318334;4316.784160791979;4333.371661109153;4274.529801721808;4278.2085314216965;4282.657387409;4330.328799036154;4352.586504282088;4303.827846886152;4315.633171180453;4325.307950727868;4361.389405483234;4361.694681372273;4495.662825288116;4600.286955521866;4647.34937108805;4664.277037059227;4671.346011558385;5040.571551418134;5088.567471764904;5088.567471764904;5105.5149760772665;5105.617078719074;5080.542751152377;5081.618878584121;5082.161443361841;5105.259737338102;5129.692218466904;4617.353570500132;4696.38946936896;4753.239481682914;4760.190767569452;4760.904781904343;5239.842804715865;5288.867778305573;5333.706646134422;5334.413214546396;5338.3593968724;4836.564709176191;5067.087924173684;5068.721220332917;5106.770195341219;5118.129917913963;5010.323606313174;5047.343845300087;5056.898862022813;5059.924101138486;5126.256692779526;5026.070177017264;5026.572741316956;5028.798848046037;5042.168042273057;5087.186050232019;4777.8088876445245;4947.6536642434685;4954.826581069645;5005.049040682259;5014.974925125424;4198.769067701244;4253.97873010633;4255.106427650167;4287.142571485715;4371.038371558252;3803.2773772983046;3816.5038544988975;3852.5569559500454;3877.515310586177;3915.980420097881;4414.646828253732;4432.512423633398;4436.511983681792;4478.373027776114;4478.865634030984;4978.925316120261;4988.426330971912;5023.1711767557945;5125.308083408767;5142.742862856842;4366.039471316305;4389.100234451929;4401.471823381215;4430.756308403004;4439.200943830077;4618.237990731492;4628.259478483426;4628.352030715702;4628.768561571908;4631.096936097429;4579.313103034438;4601.28680054592;4761.357216775829;4795.184818580919;4801.567858892647;5106.285120083266;5120.795168193379;5131.358580177478;5134.229865520202;5192.818251361185;4799.736014519235;4827;4828.5412885775795;4830.275458681226;4864.781084851119;4839.104765618372;4883.121038213156;4981.2030075187795;5077.543021128116;5121.769520371667;5084.923726144037;5118.923216151835;5119.590432765295;5138.434772175133;5161.174212126042;4970.155073637563;4977.776000079933;4980.27785971025;4984.526469985346;5025.221090730943;4695.671303008757;4839.370881785346;4877.6829506081995;4983.127952608352;4992.750362481934;4898.4121905371585;4916.631252656137;4945.184044632595;4969.428515720594;4978.079055374623;4997.650164488451;5012.223105418666;5016.372953380827;5021;5070.543651071422;4733.763311834464;4805.975970120127;4853.878653033702;4854.587360074292;4904.141775189256 17:27:25 INFO - PROCESS | 31293 | |1;ext.html;573.8163787587973;574.7155158196693;577.6678409914299;582.5397935630851;584.8070136854839;26927.461450770974;38124.23751524971;39459;39528.80235598821;43289.35065974005;39632;39920.401193982114;39981.800090999575;40111.39832902509;40200;7426.257374262585;7812.296893279604;7929.731243001121;7960.283574478296;7960.681572737083;7314.195438501761;7552.622368881562;7553.357964573018;9986.900130998705;10165.847512287322;10181.185505159607;10369.688909332694;10369.948150259239;10388.584456621726;10393.428361440117;10154.441505717183;10202.132818710419;10219;10223.693289201336;10230.23273254509;7400.629968501607;7634.198409167013;7638.388928885676;9866.45734484603;10268.92176321483;37935.051623709354;38106.61893381058;38116.237675246615;38145.61854381448;38413.231735365414;3064.632244130696;3167.287360343927;3226.4837625979967;3227.1770698471973;3577.58857731359;28014.237579611516;28771.71228287732;29086.563701544495;29201;29666.25834354137;3266.9019929402157;3313.6023677158655;3344.347852168804;3364.1900000498094;3376.6960973512255;184720;185813;185816;185823.07088464606;185887;49867;50751.23873141906;50825.491745082814;50851.74574127106;50888.49111508911;48585;48949.265761013616;48990.26514602284;49029.754851225516;49067.26399104016;14053.297335133202;14188.929055354658;14194.85805141956;14224.075435096685;14234.715305693828;10269.332493387907;11444.054510733195;11497;11511.654650360502;11524.88475115255;14532.200728960008;14978.400863965318;14982.62543436401;15497.767533487007;15520.14639194855;5681.4034526374135;5699.373068962411;5761.193432919395;5769.58645131937;5838.795642152504;792.5324058805332;839.5298632765671;860.0238729042582;941.1529623338913;986.4771671014374;27117.72882271152;27598.5860212097;28167.859160704476;28245.858770706425;28261.293467663472;935.3873213045466;949.3734135470694;968.7917097823861;984.9852252216223;1085.3487907255742;14784.778228326584;14892.404303827941;14962.551123466314;15024.248787560578;15154.545363639108;378894;383175;383559;384917;385144;48079.278810817865;48133.75933120312;48147.27779083316;48189.75905120522;48259;1084.9294795838246;1134.234391785558;1169.2458364354943;1205.1142410328227;1214.0651698192341;27437.314067147683;28839.7116028837;28940.710592894644;29179.416411671224;29352.266193344483;40054.1989160221;44447.33329000003;45758.313625296316;46184.76907615441;50803.237951431496;357752;358626;359073;360065;360560;22139.778602213773;24190;24456.877715611306;24597.754022459547;24867.751322486543;40675.1864962705;40754.59245407508;40818.1836363277;41166.79416602898;41225;34533;35142;35693.46459803157;35793.64206357903;36022;35441.29117417689;36012;36173.27653446969;36339.27321453609;36433;34648.88836095104;35095.64904351026;35250.647493525765;35275.82362088173;35415.645843541235;29853.5521967175;31332.216694582818;31428.37143257168;31655.208619783767;31919.521207181446;24298.63552046756;24930.127445539318;25086.37284067913;25121.623175652745;25381.492370152126 17:27:25 INFO - PROCESS | 31293 | |2;jquery.html;348.16266878158007;370.25948103792416;375.20831046491907;378.82195368176957;394.4517121201529;12917.806232906514;13487;15632.452864149744;17234.82765172348;17248.56878578036;15969.44106956254;16951.49145525633;17059;17244.22400991955;17390.21744021519;4982.850514484571;5189.195674670432;5199.168133098705;5204.245384419257;5207.088759467097;3822.789746563938;5805.5065319447895;6110.663913484756;6115.174451449064;6249.5625306228585;6065.939340606604;6131.264248290211;6168.475679567242;6169.753209871622;6195.070739389105;5804.9419505805035;5951.345352011275;5965.701714914237;5979.073243647242;5980.372060933583;5018;5220.321358223407;5229.738513074331;5333.866653333659;5341.893162136736;20415;21190;21499.892500537397;21558.137674493002;21565.46086347838;1900.9429717108371;1905.1522072677665;1943.2906988949073;1945.3677554794606;1958.8922609256485;12777;12834.422450989729;13042.54351097707;13268.203907765565;13776.311184440738;1717.8797484176112;1747.2836137183822;1754.4210410564483;1768.5755418699418;1784.4557409989948;64876.35123648797;65205.67397163031;66949;67377.3262267376;68270;9183;9352.205062569621;9395;9523.190528804991;9549.236061115094;12740;12770.936145319214;12788.744225115446;12793.744125117631;12850.935745321214;6878.449724022066;7009.4392448603985;7024.824379390454;7058.470614703918;7237.384822290059;6494.285628580852;6508.934910650928;6657.633830139292;6691.498137639696;6855.520113592001;5859.912101318484;6002.789902353484;6094.299155597075;6098.207233059674;6194.969025154845;1678.2867281405597;1711.9657606847793;1717.9949729408436;1743.564108972757;1752.5618595351161;803.5299349880386;875.8686197070363;909.231699214163;915.876356691838;939.3471537281661;16375.672486550202;16550.50348489547;17058.914705426392;17616.29534818593;17671.73492397615;811.5414790643267;838.3167718309559;890.8663700444985;893.3076865429344;922.4557511068367;8831.3376496763;9572.234221262283;9620.71137865865;9721.125098741148;9772.16936560386;405152;411046;411845;428029;436060;56459;72249.6387518059;83010;87142.56428717943;89998.10001900028;940.1914353655987;952.6141912525438;953.5613617735919;995.5669283861544;1001.9048190421898;15473.148976806384;16177;16442.260098295366;16585.25366358541;16613.50159495217;37744.81127594454;38576.42135368028;38778.22443551169;38790;39159.6084039156;50091.74954125206;54213;54540.45459545512;54614.72692636511;54650.72674636601;6639.435647969976;7119.822004449723;7279;7695.768677011652;8023.83952320962;11564.710882228012;11888.405579720806;12036.699082523008;12129.514819407477;12232.327222002876;10407.895921040692;10518.842217366593;10612.469376531297;10681.626143084955;11246.493907774333;10142.746431338981;10307.587696492057;10768.0847127995;10881.238313317992;11167.329960202414;10586.206034547286;10591.788164236517;10827;11004.559817607525;11529.685495024782;7097.396721278749;7169.4622903281415;7238.0952380952385;7241.275872412927;7679.654415551202;10197.54111064989;10574.735631609272;10678.62624808128;10760.085392741705;11055.72360690989 17:27:25 INFO - PROCESS | 31293 | |3;mootools.html;1332.7001424679445;1390.0061456058918;1440.373437554663;1663.1517925857809;1679.3618424998506;7390.963045184773;7822.765317040498;7899.763007109788;7905.644246008929;7908.051033875936;6653.900191497131;6881.896771548419;7263.2010478847415;7570.129435114964;7740.187280335554;2142.111023925073;2210.1601391471218;2211.6571931350586;2212.822974162063;2213.4798322394226;1829.6552034254812;2156.611809874222;2190.397640648818;2221.9000144993433;2224.3215819175143;2101.453622058268;2168.967465488019;2171.1206961180746;2194.5940001099766;2201.427628816511;2170.696102545645;2177.357679484548;2192.024549075662;2206.415299945516;2207.8233741300655;2139.539998900244;2142.924997625076;2146.8497205195645;2151.47288914217;2157.7410710714817;7696.884546731803;7791.610419479003;7798.9220107798765;7815.062192536932;7839.96080019602;4546.431696037996;4694.694844835075;4709.552592503706;4717.905641887178;4722.5513576210205;2665.893364265427;2738.780897528193;2773.9306517337027;2774.0706863200853;2788.3029242689327;874.0734821089666;879.0989236033054;881.5195718333459;894.2622336572289;894.5214310343999;10475.58097676092;10687.038166565046;10753.892461075367;10757;10761.569537218502;4093.2222877653153;4235.555266697014;4336.848210312624;4350.1734670412525;4372.409724687126;9044.81910361789;9060.184583387527;9065.501397423204;9065.637374505079;9106.544672766335;4725.1967165581245;4737.076270127361;4738.526147385234;4741.620670346433;4761.904761904742;4260.701750877471;4261.74429534229;4330.372096046056;4334.934975975363;4336.674749393745;3630.6913912317223;3680.871169509054;3723.9627603724157;3753.305638456894;3781.6029316921877;1095.4030053620636;1164.9068074554016;1174.266083697689;1180.6871179137536;1182.379250893288;790.3677058353294;873.921347078766;876.4697358098387;912.8676341930383;918.081918081918;5523.834284971457;5611.691356975405;5704.828855134353;5762.74067666956;5770.769169233267;877.8814859993947;890.470170248701;892.906244844295;913.141646851957;923.0953665407803;3670.6512881276235;3826.6747326477002;3835.4438606402473;3862.575116737157;3870.1292209252692;9420.19928306088;9715.70852874415;9785;9786.608535658635;9817.214622830157;8351.457155284887;8888.511131887679;9289.210417114486;9722.513874306256;9727.708168754949;7287.6356182190675;7417.29535694119;7484.850302993909;7489.887651685229;7492.213317601682;5464.9453505464435;5611.775528978795;5685.51673107774;5692.886142277214;5762.1068734346245;9032.683856065007;9033.548322583976;9035.367524273237;9043.095690430904;9052.185303322733;8800.559972001247;9073.954630226806;9281.582328795086;9284.118008789153;9311.674091406769;6227.750889964351;6330.588511746721;6352.777652782131;6371.649559274284;6389.7763578274535;7119.85760284781;7127.893081603676;7137.928620713727;7138.072050633385;7154.499185056996;6530.412262896362;6629.6685165740555;6717.05961165447;6776.491763117688;6841.965790171017;6520;6531.673416329259;6563.507736919654;6574.211094668671;6722.092517510074;6238.968805155945;6269.905951410641;6313.526485513513;6314.368563143833;6324.2410910691015;4541.772911354486;4710.599599034121;4735.33705281254;4747.501512341292;4885.096257192431;4716.29255611661;4777.546133117348;4829.468758436639;4886.511348865085;4911.263310503453 17:27:25 INFO - PROCESS | 31293 | |4;prototype.html;290.54384616152635;299.4370583303389;300.6527460782796;302.1373977294823;306.9033254524825;4828.010257897132;4869.7808598613055;4879.1705410080285;4891.779869905854;4926.729029903354;4807.855764327067;4809.2786082087705;4832.226843705008;4864.489228630996;4887.364642596466;3622.681483850338;3700.2044560419504;3732.2908647356917;3818.599047100056;3871.651551360377;3817.3585358295923;3827.1962887793597;3828.2152158807376;3836.3478208704446;3860.3244432224387;3602.35157671619;3775.3015692096915;3795.8481660733537;3805.6194380562;3822.8279727412196;3519.718422526217;3704.8885334399706;3704.9255715842373;3706.7405281630163;3810.2189051244427;3341.766076374655;3348.5312056312137;3356.5972083350152;3360.3783300089563;3399.2861499084975;4600.17196904557;4631.212693842055;4655.138799322136;4660.883477913079;4697.906041879143;4242.618164365191;4318.52496225415;4324.243257429968;4353.08585197109;4363.759993200372;4989.106318335357;4991.326170966946;5008.323876276692;5028.170351891933;5045.798168073273;3497.8600855965733;3675.558932928039;3678.9632103678887;3701.8889433317045;3710.7959062251566;5266.15741481365;5289.68261904287;5293.602979776532;5311.336082989626;5323.095073837419;3708.7589306694977;3726.571444283888;3727.5713292971113;3728.086618778417;3731.9253614927547;3767.6797472215167;3769.378052621342;3770.962290377116;3773.132179598708;3783.4892289541112;4116.423700681907;4238.6397156242065;4238.936415953764;4246.8301267949555;4292.6995110342605;5007.9499205008215;5038.949610503922;5041.983226689467;5056.367954005749;5080.110980578458;1281.5066199513255;1293.5835260389858;1299.4672184404349;1299.8505171905163;1303.361352937054;797.5095316380384;834.5660256666499;837.5853952293654;847.576211894053;851.723189963252;4277.802215379698;4360.7819609019425;4436.312371582345;4470.485894122153;4482.394876691605;5150.819721309736;5155.252488389163;5191.610629202825;5206.323177986837;5227.477252274742;4460;4471.932921006188;4474.239379305526;4478.126765280804;4483.991102002024;2645.1799942018038;2759.379139693553;2771.6812566555104;2778.6665600128113;2788.3168623687325;4901.313816065754;4907.435644900811;4914.262860570943;4933.728644924564;4978.182963218399;5096.337476128079;5337.386200586904;5344.305240318734;5356.892862142735;5357.133933345642;4899;4905.386826646669;4926.778294976734;4928.654994150375;4937.259411088294;5173.793048278027;5185.611079169002;5187.610929180251;5253.71104589236;5259.289995850511;4925;5095.15929871567;5105.183170692671;5129.692218466904;5132.230165475208;4852.95147048525;4875.268709693574;4893.339399181207;4893.363862697826;4900.509949005071;3666.486691863088;3673.2837096765875;3685.115572262584;3690.7047436205576;3695.3533131702384;4956.330895329084;5223.085959956917;5257.842264732064;5262.394824595221;5271.920921186262;5024.086030102459;5031.3962324519825;5032.295478633067;5037.395512538519;5048.621353398436;4987.376577927759;5001.924971125509;5021.246812978083;5063.822766203165;5071.340725705634;4774.233533592593;4944;4956.182229932021;4960.082384758831;4965.907500349917;4865.9756701216265;4907.190313598217;5049.31834202378;5056;5066.797328106834;4942.110420124268;4954.9752251238515;4957.157283261926;4966.950330496793;4972.602191824718 17:27:25 INFO - PROCESS | 31293 | |5;yui.html;588.7144734803621;616.3189675408674;678.8879834827254;680.4760334542402;716.1835507521423;12416.503339866405;13357.999999999987;13368.799468007988;13398.531051413203;13426.530071447502;12458.750824983495;12709.555165569207;12716.300603466829;12731.490740370396;12833.614991550245;612.6385432594768;626.1734510446213;724.7246046502335;725.8149171961143;753.5252790741853;741.114368329846;742.2503271695572;743.888416737491;745.7091734223657;746.358132006474;805.2792750488338;805.4965646470955;806.7741032510907;808.9676412943476;809.2393150438598;640.8974564069773;779.8440311937578;799.8400319936036;800.655718041242;802.0535767794;579.6145563200466;581.4911952041963;582.8571999860019;585.8652509922699;586.0652259645858;13557.728845423135;13569.932150339186;13579.253141077237;13579.456821727217;13585.252811095386;13359.131656442398;13895.305234738222;13920.93039534806;13925;13935.58193254204;10610.57557697699;10686.946565267202;10878.292910960763;10952.507137178845;10988.230823842334;3157.6526582075953;3161.1464904475893;3166.6991635794793;3200.1199670090678;3214.260720034405;60641.6967915162;60853.695731521504;60858.39141608572;60876.39123608752;61015.08477372843;10323.32898361604;10473;10526.157907367393;10596.629117980834;10830.079443247412;17911.19399626994;18591.25634974613;18630.16164272611;19515.121819518157;19537.413877583695;7174.569525828467;7303.780886573411;7389.039424874732;7449.366803821736;7461.291177338144;3874.535055793323;3919.862804801818;3957.9010524737114;3967.6032396760093;3970.900727481778;2832.1928250448523;2936.3686807336526;2942.7792915531422;2949.73452389286;2956.926076848096;496.2234103627883;499.8825276060123;513.4070148977862;515.8297761738618;519.7375325460694;495.970241785494;501.929729837823;532.9360476742816;547.6604505206798;564.4589279248456;8208.794780130545;8672.73981780535;8787.736367908972;8794.252488538546;8908.198262156437;507.64211231082;575.923023945225;599.4365296621162;614.2935624032309;634.660456655687;4069.8168582413864;4133.524644665842;4345.521992580813;4434.623057040188;4508.32375143731;58868.705656471444;72120;96372;109672.4516377413;110280;90760.54619726991;90786.54606726924;90903;91004;91420.5428972851;77549.22450775532;79281;79855.60072199602;79959.60020199862;81518.77721834177;7512.9624351880075;7648.847023059397;7810;7949.960250198712;8043.557604331818;47029.29456058094;47364.2895356563;47389.289160663306;48557.27164092612;48595.75702121467;61481.38518614756;71080;71096.64451677709;71884.28115718775;72342;10176;10201.183905287411;11164.17075436825;11257.605983790529;11282.717932051435;13228.404721787736;13413.530526431528;13692.931535342657;13816.654583635489;14268.14391136535;11641.06871450265;11828.940855295668;11863.324922456888;12343.13598048128;12775.23348599087;12341.321227332583;12461.626151215478;12555.681653426258;12899.290539020443;13046.34768261602;11172.21794474379;11287.887121129012;11647.359395233343;11710.473028713557;11786.057115430573;9040.864387034331;9327.300452465957;9338.859917101114;9353.579088941151;9780.902190977999;9946.110239054557;10309.432981186106;10333.793324133625;10339.844902326622;10480.737981550523 17:27:25 INFO - PROCESS | 31293 | __end_tp_report 17:27:25 INFO - PROCESS | 31293 | __start_cc_report 17:27:25 INFO - PROCESS | 31293 | _x_x_mozilla_cycle_collect,978 17:27:25 INFO - PROCESS | 31293 | __end_cc_report 17:27:25 INFO - PROCESS | 31293 | __startTimestamp1468974445660__endTimestamp 17:27:25 INFO - PROCESS | 31293 | 17:27:25 INFO - PROCESS | 31293 | ------- Summary: start ------- 17:27:25 INFO - PROCESS | 31293 | Number of tests: 6 17:27:25 INFO - PROCESS | 31293 | 17:27:25 INFO - PROCESS | 31293 | [#0] dojo.html Cycles:165 Average:4686.25 Median:4799.74 stddev:497.35 (10.4%) stddev-sans-first:471.71 17:27:25 INFO - PROCESS | 31293 | Values: 2619.5 2633.0 2633.3 2634.7 2635.4 4556.9 4635.0 4707.5 4737.2 4744.3 4646.3 4686.8 4687.9 4715.4 4720.1 4111.2 4278.3 4359.3 4388.7 4442.3 4353.8 4383.0 4398.1 4405.5 4431.4 4243.9 4280.0 4309.3 4316.8 4333.4 4274.5 4278.2 4282.7 4330.3 4352.6 4303.8 4315.6 4325.3 4361.4 4361.7 4495.7 4600.3 4647.3 4664.3 4671.3 5040.6 5088.6 5088.6 5105.5 5105.6 5080.5 5081.6 5082.2 5105.3 5129.7 4617.4 4696.4 4753.2 4760.2 4760.9 5239.8 5288.9 5333.7 5334.4 5338.4 4836.6 5067.1 5068.7 5106.8 5118.1 5010.3 5047.3 5056.9 5059.9 5126.3 5026.1 5026.6 5028.8 5042.2 5087.2 4777.8 4947.7 4954.8 5005.0 5015.0 4198.8 4254.0 4255.1 4287.1 4371.0 3803.3 3816.5 3852.6 3877.5 3916.0 4414.6 4432.5 4436.5 4478.4 4478.9 4978.9 4988.4 5023.2 5125.3 5142.7 4366.0 4389.1 4401.5 4430.8 4439.2 4618.2 4628.3 4628.4 4628.8 4631.1 4579.3 4601.3 4761.4 4795.2 4801.6 5106.3 5120.8 5131.4 5134.2 5192.8 4799.7 4827.0 4828.5 4830.3 4864.8 4839.1 4883.1 4981.2 5077.5 5121.8 5084.9 5118.9 5119.6 5138.4 5161.2 4970.2 4977.8 4980.3 4984.5 5025.2 4695.7 4839.4 4877.7 4983.1 4992.8 4898.4 4916.6 4945.2 4969.4 4978.1 4997.7 5012.2 5016.4 5021.0 5070.5 4733.8 4806.0 4853.9 4854.6 4904.1 17:27:25 INFO - PROCESS | 31293 | 17:27:25 INFO - PROCESS | 31293 | [#1] ext.html Cycles:180 Average:46305.70 Median:35567.38 stddev:84939.53 (238.8%) stddev-sans-first:85108.41 17:27:25 INFO - PROCESS | 31293 | Values: 573.8 574.7 577.7 582.5 584.8 26927.5 38124.2 39459.0 39528.8 43289.4 39632.0 39920.4 39981.8 40111.4 40200.0 7426.3 7812.3 7929.7 7960.3 7960.7 7314.2 7552.6 7553.4 9986.9 10165.8 10181.2 10369.7 10369.9 10388.6 10393.4 10154.4 10202.1 10219.0 10223.7 10230.2 7400.6 7634.2 7638.4 9866.5 10268.9 37935.1 38106.6 38116.2 38145.6 38413.2 3064.6 3167.3 3226.5 3227.2 3577.6 28014.2 28771.7 29086.6 29201.0 29666.3 3266.9 3313.6 3344.3 3364.2 3376.7 184720.0 185813.0 185816.0 185823.1 185887.0 49867.0 50751.2 50825.5 50851.7 50888.5 48585.0 48949.3 48990.3 49029.8 49067.3 14053.3 14188.9 14194.9 14224.1 14234.7 10269.3 11444.1 11497.0 11511.7 11524.9 14532.2 14978.4 14982.6 15497.8 15520.1 5681.4 5699.4 5761.2 5769.6 5838.8 792.5 839.5 860.0 941.2 986.5 27117.7 27598.6 28167.9 28245.9 28261.3 935.4 949.4 968.8 985.0 1085.3 14784.8 14892.4 14962.6 15024.2 15154.5 378894.0 383175.0 383559.0 384917.0 385144.0 48079.3 48133.8 48147.3 48189.8 48259.0 1084.9 1134.2 1169.2 1205.1 1214.1 27437.3 28839.7 28940.7 29179.4 29352.3 40054.2 44447.3 45758.3 46184.8 50803.2 357752.0 358626.0 359073.0 360065.0 360560.0 22139.8 24190.0 24456.9 24597.8 24867.8 40675.2 40754.6 40818.2 41166.8 41225.0 34533.0 35142.0 35693.5 35793.6 36022.0 35441.3 36012.0 36173.3 36339.3 36433.0 34648.9 35095.6 35250.6 35275.8 35415.6 29853.6 31332.2 31428.4 31655.2 31919.5 24298.6 24930.1 25086.4 25121.6 25381.5 17:27:25 INFO - PROCESS | 31293 | 17:27:25 INFO - PROCESS | 31293 | [#2] jquery.html Cycles:180 Average:25345.26 Median:432044.50 stddev:68939.89 (16.0%) stddev-sans-first:69107.73 17:27:25 INFO - PROCESS | 31293 | Values: 348.2 370.3 375.2 378.8 394.5 12917.8 13487.0 15632.5 17234.8 17248.6 15969.4 16951.5 17059.0 17244.2 17390.2 4982.9 5189.2 5199.2 5204.2 5207.1 3822.8 5805.5 6110.7 6115.2 6249.6 6065.9 6131.3 6168.5 6169.8 6195.1 5804.9 5951.3 5965.7 5979.1 5980.4 5018.0 5220.3 5229.7 5333.9 5341.9 20415.0 21190.0 21499.9 21558.1 21565.5 1900.9 1905.2 1943.3 1945.4 1958.9 12777.0 12834.4 13042.5 13268.2 13776.3 1717.9 1747.3 1754.4 1768.6 1784.5 64876.4 65205.7 66949.0 67377.3 68270.0 9183.0 9352.2 9395.0 9523.2 9549.2 12740.0 12770.9 12788.7 12793.7 12850.9 6878.4 7009.4 7024.8 7058.5 7237.4 6494.3 6508.9 6657.6 6691.5 6855.5 5859.9 6002.8 6094.3 6098.2 6195.0 1678.3 1712.0 1718.0 1743.6 1752.6 803.5 875.9 909.2 915.9 939.3 16375.7 16550.5 17058.9 17616.3 17671.7 811.5 838.3 890.9 893.3 922.5 8831.3 9572.2 9620.7 9721.1 9772.2 405152.0 411046.0 411845.0 428029.0 436060.0 56459.0 72249.6 83010.0 87142.6 89998.1 940.2 952.6 953.6 995.6 1001.9 15473.1 16177.0 16442.3 16585.3 16613.5 37744.8 38576.4 38778.2 38790.0 39159.6 50091.7 54213.0 54540.5 54614.7 54650.7 6639.4 7119.8 7279.0 7695.8 8023.8 11564.7 11888.4 12036.7 12129.5 12232.3 10407.9 10518.8 10612.5 10681.6 11246.5 10142.7 10307.6 10768.1 10881.2 11167.3 10586.2 10591.8 10827.0 11004.6 11529.7 7097.4 7169.5 7238.1 7241.3 7679.7 10197.5 10574.7 10678.6 10760.1 11055.7 17:27:25 INFO - PROCESS | 31293 | 17:27:25 INFO - PROCESS | 31293 | [#3] mootools.html Cycles:180 Average:5150.32 Median:5698.86 stddev:2846.52 (49.9%) stddev-sans-first:2840.05 17:27:25 INFO - PROCESS | 31293 | Values: 1332.7 1390.0 1440.4 1663.2 1679.4 7391.0 7822.8 7899.8 7905.6 7908.1 6653.9 6881.9 7263.2 7570.1 7740.2 2142.1 2210.2 2211.7 2212.8 2213.5 1829.7 2156.6 2190.4 2221.9 2224.3 2101.5 2169.0 2171.1 2194.6 2201.4 2170.7 2177.4 2192.0 2206.4 2207.8 2139.5 2142.9 2146.8 2151.5 2157.7 7696.9 7791.6 7798.9 7815.1 7840.0 4546.4 4694.7 4709.6 4717.9 4722.6 2665.9 2738.8 2773.9 2774.1 2788.3 874.1 879.1 881.5 894.3 894.5 10475.6 10687.0 10753.9 10757.0 10761.6 4093.2 4235.6 4336.8 4350.2 4372.4 9044.8 9060.2 9065.5 9065.6 9106.5 4725.2 4737.1 4738.5 4741.6 4761.9 4260.7 4261.7 4330.4 4334.9 4336.7 3630.7 3680.9 3724.0 3753.3 3781.6 1095.4 1164.9 1174.3 1180.7 1182.4 790.4 873.9 876.5 912.9 918.1 5523.8 5611.7 5704.8 5762.7 5770.8 877.9 890.5 892.9 913.1 923.1 3670.7 3826.7 3835.4 3862.6 3870.1 9420.2 9715.7 9785.0 9786.6 9817.2 8351.5 8888.5 9289.2 9722.5 9727.7 7287.6 7417.3 7484.9 7489.9 7492.2 5464.9 5611.8 5685.5 5692.9 5762.1 9032.7 9033.5 9035.4 9043.1 9052.2 8800.6 9074.0 9281.6 9284.1 9311.7 6227.8 6330.6 6352.8 6371.6 6389.8 7119.9 7127.9 7137.9 7138.1 7154.5 6530.4 6629.7 6717.1 6776.5 6842.0 6520.0 6531.7 6563.5 6574.2 6722.1 6239.0 6269.9 6313.5 6314.4 6324.2 4541.8 4710.6 4735.3 4747.5 4885.1 4716.3 4777.5 4829.5 4886.5 4911.3 17:27:25 INFO - PROCESS | 31293 | 17:27:25 INFO - PROCESS | 31293 | [#4] prototype.html Cycles:180 Average:4199.54 Median:4858.72 stddev:1216.85 (25.0%) stddev-sans-first:1184.36 17:27:25 INFO - PROCESS | 31293 | Values: 290.5 299.4 300.7 302.1 306.9 4828.0 4869.8 4879.2 4891.8 4926.7 4807.9 4809.3 4832.2 4864.5 4887.4 3622.7 3700.2 3732.3 3818.6 3871.7 3817.4 3827.2 3828.2 3836.3 3860.3 3602.4 3775.3 3795.8 3805.6 3822.8 3519.7 3704.9 3704.9 3706.7 3810.2 3341.8 3348.5 3356.6 3360.4 3399.3 4600.2 4631.2 4655.1 4660.9 4697.9 4242.6 4318.5 4324.2 4353.1 4363.8 4989.1 4991.3 5008.3 5028.2 5045.8 3497.9 3675.6 3679.0 3701.9 3710.8 5266.2 5289.7 5293.6 5311.3 5323.1 3708.8 3726.6 3727.6 3728.1 3731.9 3767.7 3769.4 3771.0 3773.1 3783.5 4116.4 4238.6 4238.9 4246.8 4292.7 5007.9 5038.9 5042.0 5056.4 5080.1 1281.5 1293.6 1299.5 1299.9 1303.4 797.5 834.6 837.6 847.6 851.7 4277.8 4360.8 4436.3 4470.5 4482.4 5150.8 5155.3 5191.6 5206.3 5227.5 4460.0 4471.9 4474.2 4478.1 4484.0 2645.2 2759.4 2771.7 2778.7 2788.3 4901.3 4907.4 4914.3 4933.7 4978.2 5096.3 5337.4 5344.3 5356.9 5357.1 4899.0 4905.4 4926.8 4928.7 4937.3 5173.8 5185.6 5187.6 5253.7 5259.3 4925.0 5095.2 5105.2 5129.7 5132.2 4853.0 4875.3 4893.3 4893.4 4900.5 3666.5 3673.3 3685.1 3690.7 3695.4 4956.3 5223.1 5257.8 5262.4 5271.9 5024.1 5031.4 5032.3 5037.4 5048.6 4987.4 5001.9 5021.2 5063.8 5071.3 4774.2 4944.0 4956.2 4960.1 4965.9 4866.0 4907.2 5049.3 5056.0 5066.8 4942.1 4955.0 4957.2 4967.0 4972.6 17:27:25 INFO - PROCESS | 31293 | 17:27:25 INFO - PROCESS | 31293 | [#5] yui.html Cycles:180 Average:18228.11 Median:24545.86 stddev:26111.87 (106.4%) stddev-sans-first:26151.53 17:27:25 INFO - PROCESS | 31293 | Values: 588.7 616.3 678.9 680.5 716.2 12416.5 13358.0 13368.8 13398.5 13426.5 12458.8 12709.6 12716.3 12731.5 12833.6 612.6 626.2 724.7 725.8 753.5 741.1 742.3 743.9 745.7 746.4 805.3 805.5 806.8 809.0 809.2 640.9 779.8 799.8 800.7 802.1 579.6 581.5 582.9 585.9 586.1 13557.7 13569.9 13579.3 13579.5 13585.3 13359.1 13895.3 13920.9 13925.0 13935.6 10610.6 10686.9 10878.3 10952.5 10988.2 3157.7 3161.1 3166.7 3200.1 3214.3 60641.7 60853.7 60858.4 60876.4 61015.1 10323.3 10473.0 10526.2 10596.6 10830.1 17911.2 18591.3 18630.2 19515.1 19537.4 7174.6 7303.8 7389.0 7449.4 7461.3 3874.5 3919.9 3957.9 3967.6 3970.9 2832.2 2936.4 2942.8 2949.7 2956.9 496.2 499.9 513.4 515.8 519.7 496.0 501.9 532.9 547.7 564.5 8208.8 8672.7 8787.7 8794.3 8908.2 507.6 575.9 599.4 614.3 634.7 4069.8 4133.5 4345.5 4434.6 4508.3 58868.7 72120.0 96372.0 109672.5 110280.0 90760.5 90786.5 90903.0 91004.0 91420.5 77549.2 79281.0 79855.6 79959.6 81518.8 7513.0 7648.8 7810.0 7950.0 8043.6 47029.3 47364.3 47389.3 48557.3 48595.8 61481.4 71080.0 71096.6 71884.3 72342.0 10176.0 10201.2 11164.2 11257.6 11282.7 13228.4 13413.5 13692.9 13816.7 14268.1 11641.1 11828.9 11863.3 12343.1 12775.2 12341.3 12461.6 12555.7 12899.3 13046.3 11172.2 11287.9 11647.4 11710.5 11786.1 9040.9 9327.3 9338.9 9353.6 9780.9 9946.1 10309.4 10333.8 10339.8 10480.7 17:27:25 INFO - PROCESS | 31293 | -------- Summary: end -------- 17:27:25 INFO - PROCESS | 31293 | 17:27:26 INFO - TEST-INFO | 31293: exit 0 17:27:26 INFO - TEST-OK | dromaeo_css | took 1118663ms 17:27:26 INFO - TEST-START | kraken 17:27:26 INFO - operating with platform_type : linux_ 17:27:26 INFO - Initialising browser for kraken test... 17:27:26 INFO - TEST-INFO | started process 31483 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphqSDDY/profile http://localhost:41665/getInfo.html) 17:27:30 INFO - PROCESS | 31483 | __metrics Screen width/height:1600/1200 17:27:30 INFO - PROCESS | 31483 | colorDepth:24 17:27:30 INFO - PROCESS | 31483 | Browser inner width/height: 1024/697 17:27:30 INFO - PROCESS | 31483 | __metrics 17:27:31 INFO - PROCESS | 31483 | 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" 17:27:31 INFO - PROCESS | 31483 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:27:32 INFO - TEST-INFO | 31483: exit 0 17:27:32 INFO - Browser initialized. 17:27:32 INFO - Running cycle 1/1 for kraken test... 17:27:32 INFO - TEST-INFO | started process 31567 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphqSDDY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:27:44 INFO - PROCESS | 31567 | RSS: Main: 159076352 17:27:44 INFO - PROCESS | 31567 | 17:27:46 INFO - PROCESS | 31567 | Cycle 1(1): loaded http://localhost:41665/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41665/tests/kraken/driver.html?testName=audio-beat-detection) 17:27:47 INFO - PROCESS | 31567 | RSS: Main: 218636288 17:27:47 INFO - PROCESS | 31567 | 17:27:50 INFO - PROCESS | 31567 | Cycle 1(1): loaded http://localhost:41665/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41665/tests/kraken/driver.html?testName=audio-dft) 17:27:50 INFO - PROCESS | 31567 | RSS: Main: 206012416 17:27:50 INFO - PROCESS | 31567 | 17:27:54 INFO - PROCESS | 31567 | Cycle 1(1): loaded http://localhost:41665/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41665/tests/kraken/driver.html?testName=audio-fft) 17:27:54 INFO - PROCESS | 31567 | RSS: Main: 272715776 17:27:54 INFO - PROCESS | 31567 | 17:27:57 INFO - PROCESS | 31567 | Cycle 1(1): loaded http://localhost:41665/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41665/tests/kraken/driver.html?testName=audio-oscillator) 17:27:57 INFO - PROCESS | 31567 | RSS: Main: 182394880 17:27:57 INFO - PROCESS | 31567 | 17:27:59 INFO - PROCESS | 31567 | Cycle 1(1): loaded http://localhost:41665/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41665/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:28:00 INFO - PROCESS | 31567 | RSS: Main: 185532416 17:28:00 INFO - PROCESS | 31567 | 17:28:03 INFO - PROCESS | 31567 | Cycle 1(1): loaded http://localhost:41665/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41665/tests/kraken/driver.html?testName=imaging-darkroom) 17:28:04 INFO - PROCESS | 31567 | RSS: Main: 221868032 17:28:04 INFO - PROCESS | 31567 | 17:28:07 INFO - PROCESS | 31567 | Cycle 1(1): loaded http://localhost:41665/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41665/tests/kraken/driver.html?testName=imaging-desaturate) 17:28:08 INFO - PROCESS | 31567 | RSS: Main: 223133696 17:28:08 INFO - PROCESS | 31567 | 17:28:11 INFO - PROCESS | 31567 | Cycle 1(1): loaded http://localhost:41665/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41665/tests/kraken/driver.html?testName=json-parse-financial) 17:28:11 INFO - PROCESS | 31567 | RSS: Main: 222810112 17:28:11 INFO - PROCESS | 31567 | 17:28:13 INFO - PROCESS | 31567 | Cycle 1(1): loaded http://localhost:41665/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41665/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:28:14 INFO - PROCESS | 31567 | RSS: Main: 211697664 17:28:14 INFO - PROCESS | 31567 | 17:28:15 INFO - PROCESS | 31567 | Cycle 1(1): loaded http://localhost:41665/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41665/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:28:16 INFO - PROCESS | 31567 | RSS: Main: 215117824 17:28:16 INFO - PROCESS | 31567 | 17:28:18 INFO - PROCESS | 31567 | Cycle 1(1): loaded http://localhost:41665/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41665/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:28:19 INFO - PROCESS | 31567 | RSS: Main: 210853888 17:28:19 INFO - PROCESS | 31567 | 17:28:22 INFO - PROCESS | 31567 | Cycle 1(1): loaded http://localhost:41665/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41665/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:28:22 INFO - PROCESS | 31567 | RSS: Main: 223686656 17:28:22 INFO - PROCESS | 31567 | 17:28:25 INFO - PROCESS | 31567 | Cycle 1(1): loaded http://localhost:41665/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41665/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:28:26 INFO - PROCESS | 31567 | RSS: Main: 224165888 17:28:26 INFO - PROCESS | 31567 | 17:28:27 INFO - PROCESS | 31567 | Cycle 1(1): loaded http://localhost:41665/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41665/tests/kraken/driver.html?testName=ai-astar) 17:28:28 INFO - PROCESS | 31567 | RSS: Main: 267345920 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | __start_tp_report 17:28:28 INFO - PROCESS | 31567 | _x_x_mozilla_page_load 17:28:28 INFO - PROCESS | 31567 | _x_x_mozilla_page_load_details 17:28:28 INFO - PROCESS | 31567 | |i|pagename|runs| 17:28:28 INFO - PROCESS | 31567 | |0;ai-astar;106;115;114;117;115;114;114;113;113;116 17:28:28 INFO - PROCESS | 31567 | |1;audio-beat-detection;151;122;125;170;125;126;157;123;154;128 17:28:28 INFO - PROCESS | 31567 | |2;audio-dft;160;162;161;160;161;163;162;160;163;162 17:28:28 INFO - PROCESS | 31567 | |3;audio-fft;75;78;76;112;80;78;75;76;111;76 17:28:28 INFO - PROCESS | 31567 | |4;audio-oscillator;86;85;118;86;85;86;121;85;85;130 17:28:28 INFO - PROCESS | 31567 | |5;imaging-gaussian-blur;118;117;117;118;117;117;117;116;117;117 17:28:28 INFO - PROCESS | 31567 | |6;imaging-darkroom;147;146;146;147;148;146;146;146;147;145 17:28:28 INFO - PROCESS | 31567 | |7;imaging-desaturate;95;94;95;96;95;95;95;95;95;95 17:28:28 INFO - PROCESS | 31567 | |8;json-parse-financial;64;62;63;63;63;63;64;63;65;63 17:28:28 INFO - PROCESS | 31567 | |9;json-stringify-tinderbox;66;67;65;66;67;66;66;67;67;68 17:28:28 INFO - PROCESS | 31567 | |10;stanford-crypto-aes;80;79;78;78;78;79;79;80;79;83 17:28:28 INFO - PROCESS | 31567 | |11;stanford-crypto-ccm;131;129;137;127;130;130;129;128;176;131 17:28:28 INFO - PROCESS | 31567 | |12;stanford-crypto-pbkdf2;170;163;168;164;163;167;166;166;164;170 17:28:28 INFO - PROCESS | 31567 | |13;stanford-crypto-sha256-iterative;67;67;67;68;67;64;66;64;65;64 17:28:28 INFO - PROCESS | 31567 | __end_tp_report 17:28:28 INFO - PROCESS | 31567 | __start_cc_report 17:28:28 INFO - PROCESS | 31567 | _x_x_mozilla_cycle_collect,1931 17:28:28 INFO - PROCESS | 31567 | __end_cc_report 17:28:28 INFO - PROCESS | 31567 | __startTimestamp1468974508234__endTimestamp 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | ------- Summary: start ------- 17:28:28 INFO - PROCESS | 31567 | Number of tests: 14 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.50 stddev:2.98 (2.6%) stddev-sans-first:1.33 17:28:28 INFO - PROCESS | 31567 | Values: 106.0 115.0 114.0 117.0 115.0 114.0 114.0 113.0 113.0 116.0 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | [#1] audio-beat-detection Cycles:10 Average:138.10 Median:139.50 stddev:17.87 (12.8%) stddev-sans-first:18.33 17:28:28 INFO - PROCESS | 31567 | Values: 151.0 122.0 125.0 170.0 125.0 126.0 157.0 123.0 154.0 128.0 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:1.17 (0.7%) stddev-sans-first:1.13 17:28:28 INFO - PROCESS | 31567 | Values: 160.0 162.0 161.0 160.0 161.0 163.0 162.0 160.0 163.0 162.0 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | [#3] audio-fft Cycles:10 Average:83.70 Median:76.00 stddev:14.74 (19.4%) stddev-sans-first:15.29 17:28:28 INFO - PROCESS | 31567 | Values: 75.0 78.0 76.0 112.0 80.0 78.0 75.0 76.0 111.0 76.0 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:85.00 stddev:18.39 (21.6%) stddev-sans-first:19.09 17:28:28 INFO - PROCESS | 31567 | Values: 86.0 85.0 118.0 86.0 85.0 86.0 121.0 85.0 85.0 130.0 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 17:28:28 INFO - PROCESS | 31567 | Values: 118.0 117.0 117.0 118.0 117.0 117.0 117.0 116.0 117.0 117.0 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.84 (0.6%) stddev-sans-first:0.87 17:28:28 INFO - PROCESS | 31567 | Values: 147.0 146.0 146.0 147.0 148.0 146.0 146.0 146.0 147.0 145.0 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 17:28:28 INFO - PROCESS | 31567 | Values: 95.0 94.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:63.00 stddev:0.82 (1.3%) stddev-sans-first:0.83 17:28:28 INFO - PROCESS | 31567 | Values: 64.0 62.0 63.0 63.0 63.0 63.0 64.0 63.0 65.0 63.0 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | [#9] json-stringify-tinderbox Cycles:10 Average:66.50 Median:67.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 17:28:28 INFO - PROCESS | 31567 | Values: 66.0 67.0 65.0 66.0 67.0 66.0 66.0 67.0 67.0 68.0 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | [#10] stanford-crypto-aes Cycles:10 Average:79.30 Median:79.00 stddev:1.49 (1.9%) stddev-sans-first:1.56 17:28:28 INFO - PROCESS | 31567 | Values: 80.0 79.0 78.0 78.0 78.0 79.0 79.0 80.0 79.0 83.0 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | [#11] stanford-crypto-ccm Cycles:10 Average:134.80 Median:130.50 stddev:14.73 (11.3%) stddev-sans-first:15.55 17:28:28 INFO - PROCESS | 31567 | Values: 131.0 129.0 137.0 127.0 130.0 130.0 129.0 128.0 176.0 131.0 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.10 Median:166.50 stddev:2.64 (1.6%) stddev-sans-first:2.40 17:28:28 INFO - PROCESS | 31567 | Values: 170.0 163.0 168.0 164.0 163.0 167.0 166.0 166.0 164.0 170.0 17:28:28 INFO - PROCESS | 31567 | 17:28:28 INFO - PROCESS | 31567 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:67.00 stddev:1.52 (2.3%) stddev-sans-first:1.56 17:28:28 INFO - PROCESS | 31567 | Values: 67.0 67.0 67.0 68.0 67.0 64.0 66.0 64.0 65.0 64.0 17:28:28 INFO - PROCESS | 31567 | -------- Summary: end -------- 17:28:28 INFO - PROCESS | 31567 | 17:28:29 INFO - TEST-INFO | 31567: exit 0 17:28:29 INFO - TEST-OK | kraken | took 62809ms 17:28:29 INFO - SUITE-END | took 1181s 17:28:29 INFO - Completed test suite (00:19:41) 17:28:29 INFO - javascript benchmark 17:28:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2619.476104779044, 2633.0257804612297, 2633.3285012321853, 2634.723354047825, 2635.407033417481, 4556.886077848056, 4635.026644404671, 4707.529247075291, 4737.242041273393, 4744.264638980955, 4646.3495110684535, 4686.835960741366, 4687.921777991064, 4715.41057367829, 4720.103180395731, 4111.218868415007, 4278.25130602145, 4359.3025115981445, 4388.736675799446, 4442.3336499525, 4353.825846966118, 4383.035732138925, 4398.142362239365, 4405.471343438792, 4431.423914891076, 4243.936340954888, 4280.015596412843, 4309.310510318334, 4316.784160791979, 4333.371661109153, 4274.529801721808, 4278.2085314216965, 4282.657387409, 4330.328799036154, 4352.586504282088, 4303.827846886152, 4315.633171180453, 4325.307950727868, 4361.389405483234, 4361.694681372273, 4495.662825288116, 4600.286955521866, 4647.34937108805, 4664.277037059227, 4671.346011558385, 5040.571551418134, 5088.567471764904, 5088.567471764904, 5105.5149760772665, 5105.617078719074, 5080.542751152377, 5081.618878584121, 5082.161443361841, 5105.259737338102, 5129.692218466904, 4617.353570500132, 4696.38946936896, 4753.239481682914, 4760.190767569452, 4760.904781904343, 5239.842804715865, 5288.867778305573, 5333.706646134422, 5334.413214546396, 5338.3593968724, 4836.564709176191, 5067.087924173684, 5068.721220332917, 5106.770195341219, 5118.129917913963, 5010.323606313174, 5047.343845300087, 5056.898862022813, 5059.924101138486, 5126.256692779526, 5026.070177017264, 5026.572741316956, 5028.798848046037, 5042.168042273057, 5087.186050232019, 4777.8088876445245, 4947.6536642434685, 4954.826581069645, 5005.049040682259, 5014.974925125424, 4198.769067701244, 4253.97873010633, 4255.106427650167, 4287.142571485715, 4371.038371558252, 3803.2773772983046, 3816.5038544988975, 3852.5569559500454, 3877.515310586177, 3915.980420097881, 4414.646828253732, 4432.512423633398, 4436.511983681792, 4478.373027776114, 4478.865634030984, 4978.925316120261, 4988.426330971912, 5023.1711767557945, 5125.308083408767, 5142.742862856842, 4366.039471316305, 4389.100234451929, 4401.471823381215, 4430.756308403004, 4439.200943830077, 4618.237990731492, 4628.259478483426, 4628.352030715702, 4628.768561571908, 4631.096936097429, 4579.313103034438, 4601.28680054592, 4761.357216775829, 4795.184818580919, 4801.567858892647, 5106.285120083266, 5120.795168193379, 5131.358580177478, 5134.229865520202, 5192.818251361185, 4799.736014519235, 4827.0, 4828.5412885775795, 4830.275458681226, 4864.781084851119, 4839.104765618372, 4883.121038213156, 4981.2030075187795, 5077.543021128116, 5121.769520371667, 5084.923726144037, 5118.923216151835, 5119.590432765295, 5138.434772175133, 5161.174212126042, 4970.155073637563, 4977.776000079933, 4980.27785971025, 4984.526469985346, 5025.221090730943, 4695.671303008757, 4839.370881785346, 4877.6829506081995, 4983.127952608352, 4992.750362481934, 4898.4121905371585, 4916.631252656137, 4945.184044632595, 4969.428515720594, 4978.079055374623, 4997.650164488451, 5012.223105418666, 5016.372953380827, 5021.0, 5070.543651071422, 4733.763311834464, 4805.975970120127, 4853.878653033702, 4854.587360074292, 4904.141775189256], "value": 4654.336677033259, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.8163787587973, 574.7155158196693, 577.6678409914299, 582.5397935630851, 584.8070136854839, 26927.461450770974, 38124.23751524971, 39459.0, 39528.80235598821, 43289.35065974005, 39632.0, 39920.401193982114, 39981.800090999575, 40111.39832902509, 40200.0, 7426.257374262585, 7812.296893279604, 7929.731243001121, 7960.283574478296, 7960.681572737083, 7314.195438501761, 7552.622368881562, 7553.357964573018, 9986.900130998705, 10165.847512287322, 10181.185505159607, 10369.688909332694, 10369.948150259239, 10388.584456621726, 10393.428361440117, 10154.441505717183, 10202.132818710419, 10219.0, 10223.693289201336, 10230.23273254509, 7400.629968501607, 7634.198409167013, 7638.388928885676, 9866.45734484603, 10268.92176321483, 37935.051623709354, 38106.61893381058, 38116.237675246615, 38145.61854381448, 38413.231735365414, 3064.632244130696, 3167.287360343927, 3226.4837625979967, 3227.1770698471973, 3577.58857731359, 28014.237579611516, 28771.71228287732, 29086.563701544495, 29201.0, 29666.25834354137, 3266.9019929402157, 3313.6023677158655, 3344.347852168804, 3364.1900000498094, 3376.6960973512255, 184720.0, 185813.0, 185816.0, 185823.07088464606, 185887.0, 49867.0, 50751.23873141906, 50825.491745082814, 50851.74574127106, 50888.49111508911, 48585.0, 48949.265761013616, 48990.26514602284, 49029.754851225516, 49067.26399104016, 14053.297335133202, 14188.929055354658, 14194.85805141956, 14224.075435096685, 14234.715305693828, 10269.332493387907, 11444.054510733195, 11497.0, 11511.654650360502, 11524.88475115255, 14532.200728960008, 14978.400863965318, 14982.62543436401, 15497.767533487007, 15520.14639194855, 5681.4034526374135, 5699.373068962411, 5761.193432919395, 5769.58645131937, 5838.795642152504, 792.5324058805332, 839.5298632765671, 860.0238729042582, 941.1529623338913, 986.4771671014374, 27117.72882271152, 27598.5860212097, 28167.859160704476, 28245.858770706425, 28261.293467663472, 935.3873213045466, 949.3734135470694, 968.7917097823861, 984.9852252216223, 1085.3487907255742, 14784.778228326584, 14892.404303827941, 14962.551123466314, 15024.248787560578, 15154.545363639108, 378894.0, 383175.0, 383559.0, 384917.0, 385144.0, 48079.278810817865, 48133.75933120312, 48147.27779083316, 48189.75905120522, 48259.0, 1084.9294795838246, 1134.234391785558, 1169.2458364354943, 1205.1142410328227, 1214.0651698192341, 27437.314067147683, 28839.7116028837, 28940.710592894644, 29179.416411671224, 29352.266193344483, 40054.1989160221, 44447.33329000003, 45758.313625296316, 46184.76907615441, 50803.237951431496, 357752.0, 358626.0, 359073.0, 360065.0, 360560.0, 22139.778602213773, 24190.0, 24456.877715611306, 24597.754022459547, 24867.751322486543, 40675.1864962705, 40754.59245407508, 40818.1836363277, 41166.79416602898, 41225.0, 34533.0, 35142.0, 35693.46459803157, 35793.64206357903, 36022.0, 35441.29117417689, 36012.0, 36173.27653446969, 36339.27321453609, 36433.0, 34648.88836095104, 35095.64904351026, 35250.647493525765, 35275.82362088173, 35415.645843541235, 29853.5521967175, 31332.216694582818, 31428.37143257168, 31655.208619783767, 31919.521207181446, 24298.63552046756, 24930.127445539318, 25086.37284067913, 25121.623175652745, 25381.492370152126], "value": 17446.63275278758, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.16266878158007, 370.25948103792416, 375.20831046491907, 378.82195368176957, 394.4517121201529, 12917.806232906514, 13487.0, 15632.452864149744, 17234.82765172348, 17248.56878578036, 15969.44106956254, 16951.49145525633, 17059.0, 17244.22400991955, 17390.21744021519, 4982.850514484571, 5189.195674670432, 5199.168133098705, 5204.245384419257, 5207.088759467097, 3822.789746563938, 5805.5065319447895, 6110.663913484756, 6115.174451449064, 6249.5625306228585, 6065.939340606604, 6131.264248290211, 6168.475679567242, 6169.753209871622, 6195.070739389105, 5804.9419505805035, 5951.345352011275, 5965.701714914237, 5979.073243647242, 5980.372060933583, 5018.0, 5220.321358223407, 5229.738513074331, 5333.866653333659, 5341.893162136736, 20415.0, 21190.0, 21499.892500537397, 21558.137674493002, 21565.46086347838, 1900.9429717108371, 1905.1522072677665, 1943.2906988949073, 1945.3677554794606, 1958.8922609256485, 12777.0, 12834.422450989729, 13042.54351097707, 13268.203907765565, 13776.311184440738, 1717.8797484176112, 1747.2836137183822, 1754.4210410564483, 1768.5755418699418, 1784.4557409989948, 64876.35123648797, 65205.67397163031, 66949.0, 67377.3262267376, 68270.0, 9183.0, 9352.205062569621, 9395.0, 9523.190528804991, 9549.236061115094, 12740.0, 12770.936145319214, 12788.744225115446, 12793.744125117631, 12850.935745321214, 6878.449724022066, 7009.4392448603985, 7024.824379390454, 7058.470614703918, 7237.384822290059, 6494.285628580852, 6508.934910650928, 6657.633830139292, 6691.498137639696, 6855.520113592001, 5859.912101318484, 6002.789902353484, 6094.299155597075, 6098.207233059674, 6194.969025154845, 1678.2867281405597, 1711.9657606847793, 1717.9949729408436, 1743.564108972757, 1752.5618595351161, 803.5299349880386, 875.8686197070363, 909.231699214163, 915.876356691838, 939.3471537281661, 16375.672486550202, 16550.50348489547, 17058.914705426392, 17616.29534818593, 17671.73492397615, 811.5414790643267, 838.3167718309559, 890.8663700444985, 893.3076865429344, 922.4557511068367, 8831.3376496763, 9572.234221262283, 9620.71137865865, 9721.125098741148, 9772.16936560386, 405152.0, 411046.0, 411845.0, 428029.0, 436060.0, 56459.0, 72249.6387518059, 83010.0, 87142.56428717943, 89998.10001900028, 940.1914353655987, 952.6141912525438, 953.5613617735919, 995.5669283861544, 1001.9048190421898, 15473.148976806384, 16177.0, 16442.260098295366, 16585.25366358541, 16613.50159495217, 37744.81127594454, 38576.42135368028, 38778.22443551169, 38790.0, 39159.6084039156, 50091.74954125206, 54213.0, 54540.45459545512, 54614.72692636511, 54650.72674636601, 6639.435647969976, 7119.822004449723, 7279.0, 7695.768677011652, 8023.83952320962, 11564.710882228012, 11888.405579720806, 12036.699082523008, 12129.514819407477, 12232.327222002876, 10407.895921040692, 10518.842217366593, 10612.469376531297, 10681.626143084955, 11246.493907774333, 10142.746431338981, 10307.587696492057, 10768.0847127995, 10881.238313317992, 11167.329960202414, 10586.206034547286, 10591.788164236517, 10827.0, 11004.559817607525, 11529.685495024782, 7097.396721278749, 7169.4622903281415, 7238.0952380952385, 7241.275872412927, 7679.654415551202, 10197.54111064989, 10574.735631609272, 10678.62624808128, 10760.085392741705, 11055.72360690989], "value": 8441.03113088611, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.7001424679445, 1390.0061456058918, 1440.373437554663, 1663.1517925857809, 1679.3618424998506, 7390.963045184773, 7822.765317040498, 7899.763007109788, 7905.644246008929, 7908.051033875936, 6653.900191497131, 6881.896771548419, 7263.2010478847415, 7570.129435114964, 7740.187280335554, 2142.111023925073, 2210.1601391471218, 2211.6571931350586, 2212.822974162063, 2213.4798322394226, 1829.6552034254812, 2156.611809874222, 2190.397640648818, 2221.9000144993433, 2224.3215819175143, 2101.453622058268, 2168.967465488019, 2171.1206961180746, 2194.5940001099766, 2201.427628816511, 2170.696102545645, 2177.357679484548, 2192.024549075662, 2206.415299945516, 2207.8233741300655, 2139.539998900244, 2142.924997625076, 2146.8497205195645, 2151.47288914217, 2157.7410710714817, 7696.884546731803, 7791.610419479003, 7798.9220107798765, 7815.062192536932, 7839.96080019602, 4546.431696037996, 4694.694844835075, 4709.552592503706, 4717.905641887178, 4722.5513576210205, 2665.893364265427, 2738.780897528193, 2773.9306517337027, 2774.0706863200853, 2788.3029242689327, 874.0734821089666, 879.0989236033054, 881.5195718333459, 894.2622336572289, 894.5214310343999, 10475.58097676092, 10687.038166565046, 10753.892461075367, 10757.0, 10761.569537218502, 4093.2222877653153, 4235.555266697014, 4336.848210312624, 4350.1734670412525, 4372.409724687126, 9044.81910361789, 9060.184583387527, 9065.501397423204, 9065.637374505079, 9106.544672766335, 4725.1967165581245, 4737.076270127361, 4738.526147385234, 4741.620670346433, 4761.904761904742, 4260.701750877471, 4261.74429534229, 4330.372096046056, 4334.934975975363, 4336.674749393745, 3630.6913912317223, 3680.871169509054, 3723.9627603724157, 3753.305638456894, 3781.6029316921877, 1095.4030053620636, 1164.9068074554016, 1174.266083697689, 1180.6871179137536, 1182.379250893288, 790.3677058353294, 873.921347078766, 876.4697358098387, 912.8676341930383, 918.081918081918, 5523.834284971457, 5611.691356975405, 5704.828855134353, 5762.74067666956, 5770.769169233267, 877.8814859993947, 890.470170248701, 892.906244844295, 913.141646851957, 923.0953665407803, 3670.6512881276235, 3826.6747326477002, 3835.4438606402473, 3862.575116737157, 3870.1292209252692, 9420.19928306088, 9715.70852874415, 9785.0, 9786.608535658635, 9817.214622830157, 8351.457155284887, 8888.511131887679, 9289.210417114486, 9722.513874306256, 9727.708168754949, 7287.6356182190675, 7417.29535694119, 7484.850302993909, 7489.887651685229, 7492.213317601682, 5464.9453505464435, 5611.775528978795, 5685.51673107774, 5692.886142277214, 5762.1068734346245, 9032.683856065007, 9033.548322583976, 9035.367524273237, 9043.095690430904, 9052.185303322733, 8800.559972001247, 9073.954630226806, 9281.582328795086, 9284.118008789153, 9311.674091406769, 6227.750889964351, 6330.588511746721, 6352.777652782131, 6371.649559274284, 6389.7763578274535, 7119.85760284781, 7127.893081603676, 7137.928620713727, 7138.072050633385, 7154.499185056996, 6530.412262896362, 6629.6685165740555, 6717.05961165447, 6776.491763117688, 6841.965790171017, 6520.0, 6531.673416329259, 6563.507736919654, 6574.211094668671, 6722.092517510074, 6238.968805155945, 6269.905951410641, 6313.526485513513, 6314.368563143833, 6324.2410910691015, 4541.772911354486, 4710.599599034121, 4735.33705281254, 4747.501512341292, 4885.096257192431, 4716.29255611661, 4777.546133117348, 4829.468758436639, 4886.511348865085, 4911.263310503453], "value": 4161.343343161021, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.54384616152635, 299.4370583303389, 300.6527460782796, 302.1373977294823, 306.9033254524825, 4828.010257897132, 4869.7808598613055, 4879.1705410080285, 4891.779869905854, 4926.729029903354, 4807.855764327067, 4809.2786082087705, 4832.226843705008, 4864.489228630996, 4887.364642596466, 3622.681483850338, 3700.2044560419504, 3732.2908647356917, 3818.599047100056, 3871.651551360377, 3817.3585358295923, 3827.1962887793597, 3828.2152158807376, 3836.3478208704446, 3860.3244432224387, 3602.35157671619, 3775.3015692096915, 3795.8481660733537, 3805.6194380562, 3822.8279727412196, 3519.718422526217, 3704.8885334399706, 3704.9255715842373, 3706.7405281630163, 3810.2189051244427, 3341.766076374655, 3348.5312056312137, 3356.5972083350152, 3360.3783300089563, 3399.2861499084975, 4600.17196904557, 4631.212693842055, 4655.138799322136, 4660.883477913079, 4697.906041879143, 4242.618164365191, 4318.52496225415, 4324.243257429968, 4353.08585197109, 4363.759993200372, 4989.106318335357, 4991.326170966946, 5008.323876276692, 5028.170351891933, 5045.798168073273, 3497.8600855965733, 3675.558932928039, 3678.9632103678887, 3701.8889433317045, 3710.7959062251566, 5266.15741481365, 5289.68261904287, 5293.602979776532, 5311.336082989626, 5323.095073837419, 3708.7589306694977, 3726.571444283888, 3727.5713292971113, 3728.086618778417, 3731.9253614927547, 3767.6797472215167, 3769.378052621342, 3770.962290377116, 3773.132179598708, 3783.4892289541112, 4116.423700681907, 4238.6397156242065, 4238.936415953764, 4246.8301267949555, 4292.6995110342605, 5007.9499205008215, 5038.949610503922, 5041.983226689467, 5056.367954005749, 5080.110980578458, 1281.5066199513255, 1293.5835260389858, 1299.4672184404349, 1299.8505171905163, 1303.361352937054, 797.5095316380384, 834.5660256666499, 837.5853952293654, 847.576211894053, 851.723189963252, 4277.802215379698, 4360.7819609019425, 4436.312371582345, 4470.485894122153, 4482.394876691605, 5150.819721309736, 5155.252488389163, 5191.610629202825, 5206.323177986837, 5227.477252274742, 4460.0, 4471.932921006188, 4474.239379305526, 4478.126765280804, 4483.991102002024, 2645.1799942018038, 2759.379139693553, 2771.6812566555104, 2778.6665600128113, 2788.3168623687325, 4901.313816065754, 4907.435644900811, 4914.262860570943, 4933.728644924564, 4978.182963218399, 5096.337476128079, 5337.386200586904, 5344.305240318734, 5356.892862142735, 5357.133933345642, 4899.0, 4905.386826646669, 4926.778294976734, 4928.654994150375, 4937.259411088294, 5173.793048278027, 5185.611079169002, 5187.610929180251, 5253.71104589236, 5259.289995850511, 4925.0, 5095.15929871567, 5105.183170692671, 5129.692218466904, 5132.230165475208, 4852.95147048525, 4875.268709693574, 4893.339399181207, 4893.363862697826, 4900.509949005071, 3666.486691863088, 3673.2837096765875, 3685.115572262584, 3690.7047436205576, 3695.3533131702384, 4956.330895329084, 5223.085959956917, 5257.842264732064, 5262.394824595221, 5271.920921186262, 5024.086030102459, 5031.3962324519825, 5032.295478633067, 5037.395512538519, 5048.621353398436, 4987.376577927759, 5001.924971125509, 5021.246812978083, 5063.822766203165, 5071.340725705634, 4774.233533592593, 4944.0, 4956.182229932021, 4960.082384758831, 4965.907500349917, 4865.9756701216265, 4907.190313598217, 5049.31834202378, 5056.0, 5066.797328106834, 4942.110420124268, 4954.9752251238515, 4957.157283261926, 4966.950330496793, 4972.602191824718], "value": 3810.8519135510583, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.7144734803621, 616.3189675408674, 678.8879834827254, 680.4760334542402, 716.1835507521423, 12416.503339866405, 13357.999999999987, 13368.799468007988, 13398.531051413203, 13426.530071447502, 12458.750824983495, 12709.555165569207, 12716.300603466829, 12731.490740370396, 12833.614991550245, 612.6385432594768, 626.1734510446213, 724.7246046502335, 725.8149171961143, 753.5252790741853, 741.114368329846, 742.2503271695572, 743.888416737491, 745.7091734223657, 746.358132006474, 805.2792750488338, 805.4965646470955, 806.7741032510907, 808.9676412943476, 809.2393150438598, 640.8974564069773, 779.8440311937578, 799.8400319936036, 800.655718041242, 802.0535767794, 579.6145563200466, 581.4911952041963, 582.8571999860019, 585.8652509922699, 586.0652259645858, 13557.728845423135, 13569.932150339186, 13579.253141077237, 13579.456821727217, 13585.252811095386, 13359.131656442398, 13895.305234738222, 13920.93039534806, 13925.0, 13935.58193254204, 10610.57557697699, 10686.946565267202, 10878.292910960763, 10952.507137178845, 10988.230823842334, 3157.6526582075953, 3161.1464904475893, 3166.6991635794793, 3200.1199670090678, 3214.260720034405, 60641.6967915162, 60853.695731521504, 60858.39141608572, 60876.39123608752, 61015.08477372843, 10323.32898361604, 10473.0, 10526.157907367393, 10596.629117980834, 10830.079443247412, 17911.19399626994, 18591.25634974613, 18630.16164272611, 19515.121819518157, 19537.413877583695, 7174.569525828467, 7303.780886573411, 7389.039424874732, 7449.366803821736, 7461.291177338144, 3874.535055793323, 3919.862804801818, 3957.9010524737114, 3967.6032396760093, 3970.900727481778, 2832.1928250448523, 2936.3686807336526, 2942.7792915531422, 2949.73452389286, 2956.926076848096, 496.2234103627883, 499.8825276060123, 513.4070148977862, 515.8297761738618, 519.7375325460694, 495.970241785494, 501.929729837823, 532.9360476742816, 547.6604505206798, 564.4589279248456, 8208.794780130545, 8672.73981780535, 8787.736367908972, 8794.252488538546, 8908.198262156437, 507.64211231082, 575.923023945225, 599.4365296621162, 614.2935624032309, 634.660456655687, 4069.8168582413864, 4133.524644665842, 4345.521992580813, 4434.623057040188, 4508.32375143731, 58868.705656471444, 72120.0, 96372.0, 109672.4516377413, 110280.0, 90760.54619726991, 90786.54606726924, 90903.0, 91004.0, 91420.5428972851, 77549.22450775532, 79281.0, 79855.60072199602, 79959.60020199862, 81518.77721834177, 7512.9624351880075, 7648.847023059397, 7810.0, 7949.960250198712, 8043.557604331818, 47029.29456058094, 47364.2895356563, 47389.289160663306, 48557.27164092612, 48595.75702121467, 61481.38518614756, 71080.0, 71096.64451677709, 71884.28115718775, 72342.0, 10176.0, 10201.183905287411, 11164.17075436825, 11257.605983790529, 11282.717932051435, 13228.404721787736, 13413.530526431528, 13692.931535342657, 13816.654583635489, 14268.14391136535, 11641.06871450265, 11828.940855295668, 11863.324922456888, 12343.13598048128, 12775.23348599087, 12341.321227332583, 12461.626151215478, 12555.681653426258, 12899.290539020443, 13046.34768261602, 11172.21794474379, 11287.887121129012, 11647.359395233343, 11710.473028713557, 11786.057115430573, 9040.864387034331, 9327.300452465957, 9338.859917101114, 9353.579088941151, 9780.902190977999, 9946.110239054557, 10309.432981186106, 10333.793324133625, 10339.844902326622, 10480.737981550523], "value": 6609.694868097434, "unit": "score"}], "name": "dromaeo_css", "value": 6447.7558007843145, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 114.0, 117.0, 115.0, 114.0, 114.0, 113.0, 113.0, 116.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 122.0, 125.0, 170.0, 125.0, 126.0, 157.0, 123.0, 154.0, 128.0], "value": 138.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 161.0, 160.0, 161.0, 163.0, 162.0, 160.0, 163.0, 162.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 76.0, 112.0, 80.0, 78.0, 75.0, 76.0, 111.0, 76.0], "value": 83.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 118.0, 86.0, 85.0, 86.0, 121.0, 85.0, 85.0, 130.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 148.0, 146.0, 146.0, 146.0, 147.0, 145.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 63.0, 63.0, 63.0, 64.0, 63.0, 65.0, 63.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 66.0, 67.0, 66.0, 66.0, 67.0, 67.0, 68.0], "value": 66.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 78.0, 78.0, 78.0, 79.0, 79.0, 80.0, 79.0, 83.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 129.0, 137.0, 127.0, 130.0, 130.0, 129.0, 128.0, 176.0, 131.0], "value": 134.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 163.0, 168.0, 164.0, 163.0, 167.0, 166.0, 166.0, 164.0, 170.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 67.0, 64.0, 66.0, 64.0, 65.0, 64.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1528.0, "alertThreshold": 2.0}]} 17:28:29 INFO - Return code: 0 17:28:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:28:29 INFO - # TBPL SUCCESS # 17:28:29 INFO - Running post-action listener: _resource_record_post_action 17:28:29 INFO - ##### 17:28:29 INFO - ##### Finished run-tests step (success) 17:28:29 INFO - ##### 17:28:29 INFO - Running post-run listener: _resource_record_post_run 17:28:29 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 547131392; Read time: 16; Write time: 496896 17:28:29 INFO - TinderboxPrint: CPU usage
13.0% 17:28:29 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 17:28:29 INFO - TinderboxPrint: I/O write bytes / time
547,131,392 / 496,896 17:28:29 INFO - TinderboxPrint: CPU idle
8,320.2 (87.0%) 17:28:29 INFO - TinderboxPrint: CPU user
1,201.6 (12.6%) 17:28:29 INFO - TinderboxPrint: Swap in / out
0 / 0 17:28:29 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 257794048; Read time: 0; Write time: 252188 17:28:29 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289054720; Read time: 16; Write time: 244688 17:28:30 INFO - Running post-run listener: _upload_blobber_files 17:28:30 INFO - Blob upload gear active. 17:28:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:28:30 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:28:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:28:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:28:30 INFO - (blobuploader) - INFO - Open directory for files ... 17:28:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:28:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:28:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:28:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:28:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:28:42 INFO - (blobuploader) - INFO - Done attempting. 17:28:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:28:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:28:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:28:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:28:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:28:43 INFO - (blobuploader) - INFO - Done attempting. 17:28:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:28:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:28:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:28:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:28:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:28:44 INFO - (blobuploader) - INFO - Done attempting. 17:28:44 INFO - (blobuploader) - INFO - Iteration through files over. 17:28:44 INFO - Return code: 0 17:28:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:28:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:28:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5cc37cf44c4f2e596edfd3a99de6c7fddfd729f5bd0145b4abff2294cade6a44ac667869806e84c3ba2f8a834a0da0e8de9a1b2b6f739dcc52b2de5818720c06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5193b1054041f029bce81248c55c5a5543cbf724a434ddc8c25ea2efb77c806643e2ce76a9bc005c7e6a21be64a96247359d6ff69d7b6c342e3d05845f8a9ba5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/521040a343e5b31bfb0d3484fe3b83b61e976c1a12e26e8e80505d1303a981c62f088f060b198763a95c24afd717a98f9eed033bdf66c447b65b1a332e9de3a6"} 17:28:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:28:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:28:44 INFO - Contents: 17:28:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5cc37cf44c4f2e596edfd3a99de6c7fddfd729f5bd0145b4abff2294cade6a44ac667869806e84c3ba2f8a834a0da0e8de9a1b2b6f739dcc52b2de5818720c06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5193b1054041f029bce81248c55c5a5543cbf724a434ddc8c25ea2efb77c806643e2ce76a9bc005c7e6a21be64a96247359d6ff69d7b6c342e3d05845f8a9ba5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/521040a343e5b31bfb0d3484fe3b83b61e976c1a12e26e8e80505d1303a981c62f088f060b198763a95c24afd717a98f9eed033bdf66c447b65b1a332e9de3a6"} 17:28:44 INFO - Running post-run listener: copy_logs_to_upload_dir 17:28:44 INFO - Copying logs to upload dir... 17:28:44 INFO - mkdir: /builds/slave/test/build/upload/logs 17:28:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.309890 ========= master_lag: 0.17 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-07-19 17:28:43.906326) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 17:28:43.910158) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808 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-Non-PGO/sha512/5cc37cf44c4f2e596edfd3a99de6c7fddfd729f5bd0145b4abff2294cade6a44ac667869806e84c3ba2f8a834a0da0e8de9a1b2b6f739dcc52b2de5818720c06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5193b1054041f029bce81248c55c5a5543cbf724a434ddc8c25ea2efb77c806643e2ce76a9bc005c7e6a21be64a96247359d6ff69d7b6c342e3d05845f8a9ba5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/521040a343e5b31bfb0d3484fe3b83b61e976c1a12e26e8e80505d1303a981c62f088f060b198763a95c24afd717a98f9eed033bdf66c447b65b1a332e9de3a6"} build_url:https://queue.taskcluster.net/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011318 build_url: 'https://queue.taskcluster.net/v1/task/ci0RS9xERuSTLwjFJ8Td4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5cc37cf44c4f2e596edfd3a99de6c7fddfd729f5bd0145b4abff2294cade6a44ac667869806e84c3ba2f8a834a0da0e8de9a1b2b6f739dcc52b2de5818720c06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5193b1054041f029bce81248c55c5a5543cbf724a434ddc8c25ea2efb77c806643e2ce76a9bc005c7e6a21be64a96247359d6ff69d7b6c342e3d05845f8a9ba5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/521040a343e5b31bfb0d3484fe3b83b61e976c1a12e26e8e80505d1303a981c62f088f060b198763a95c24afd717a98f9eed033bdf66c447b65b1a332e9de3a6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 17:28:43.943705) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 17:28:43.944197) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468971167.374809-1499931808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004430 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 17:28:43.997778) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 17:28:43.998193) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 17:28:43.998758) ========= ========= Total master_lag: 0.30 =========