builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-045 starttime: 1468329618.06 results: success (0) buildid: 20160712050428 builduid: 0f091952f68949979462673a70043946 revision: 621f32c868bb5654a1ae2dc25cbb622b3c0efd69 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 06:20:18.056195) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 06:20:18.056796) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 06:20:18.057119) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 06:20:18.074428) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:20:18.075036) ========= 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=4300f881736b422476174874000001bc-1468329315.267517-1773959525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-12 06:20:18-- 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% 47.3M=0s 2016-07-12 06:20:18 (47.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081686 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:20:18.217312) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:20:18.217650) ========= 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=4300f881736b422476174874000001bc-1468329315.267517-1773959525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015027 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:20:18.283031) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:20:18.283393) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 621f32c868bb5654a1ae2dc25cbb622b3c0efd69 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 621f32c868bb5654a1ae2dc25cbb622b3c0efd69 --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=4300f881736b422476174874000001bc-1468329315.267517-1773959525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-12 06:20:18,550 truncating revision to first 12 chars 2016-07-12 06:20:18,550 Setting DEBUG logging. 2016-07-12 06:20:18,550 attempt 1/10 2016-07-12 06:20:18,550 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/621f32c868bb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 06:20:18,844 unpacking tar archive at: autoland-621f32c868bb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.666330 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:20:18.969641) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 06:20:18.970009) ========= script_repo_revision: 621f32c868bb5654a1ae2dc25cbb622b3c0efd69 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 06:20:18.970477) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 06:20:18.970795) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 06:20:18.986481) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-07-12 06:20:18.986793) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=4300f881736b422476174874000001bc-1468329315.267517-1773959525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:20:19 INFO - MultiFileLogger online at 20160712 06:20:19 in /builds/slave/test 06:20:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 06:20:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:20:19 INFO - {'append_to_log': False, 06:20:19 INFO - 'base_work_dir': '/builds/slave/test', 06:20:19 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 06:20:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:20:19 INFO - 'branch': 'Autoland-Non-PGO', 06:20:19 INFO - 'buildbot_json_path': 'buildprops.json', 06:20:19 INFO - 'config_files': ('talos/linux_config.py',), 06:20:19 INFO - 'default_actions': ('clobber', 06:20:19 INFO - 'read-buildbot-config', 06:20:19 INFO - 'download-and-extract', 06:20:19 INFO - 'populate-webroot', 06:20:19 INFO - 'create-virtualenv', 06:20:19 INFO - 'install', 06:20:19 INFO - 'run-tests'), 06:20:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:20:19 INFO - 'download_minidump_stackwalk': True, 06:20:19 INFO - 'download_symbols': 'ondemand', 06:20:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:20:19 INFO - 'tooltool.py': '/tools/tooltool.py', 06:20:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:20:19 INFO - '/tools/misc-python/virtualenv.py')}, 06:20:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:20:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:20:19 INFO - 'installer_path': 'installer.exe', 06:20:19 INFO - 'log_level': 'info', 06:20:19 INFO - 'log_name': 'talos', 06:20:19 INFO - 'log_to_console': True, 06:20:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:20:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:20:19 INFO - 'opt_config_files': (), 06:20:19 INFO - 'pip_index': False, 06:20:19 INFO - 'sps_profile': False, 06:20:19 INFO - 'sps_profile_interval': 0, 06:20:19 INFO - 'suite': 'dromaeojs', 06:20:19 INFO - 'system_bits': '32', 06:20:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:20:19 INFO - 'title': 'talos-linux64-ix-045', 06:20:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:20:19 INFO - 'use_talos_json': True, 06:20:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:20:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:20:19 INFO - 'work_dir': 'build'} 06:20:19 INFO - ##### 06:20:19 INFO - ##### Running clobber step. 06:20:19 INFO - ##### 06:20:19 INFO - Running pre-action listener: _resource_record_pre_action 06:20:19 INFO - Running main action method: clobber 06:20:19 INFO - rmtree: /builds/slave/test/build 06:20:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:20:20 INFO - Running post-action listener: _resource_record_post_action 06:20:20 INFO - ##### 06:20:20 INFO - ##### Finished clobber step (success) 06:20:20 INFO - ##### 06:20:20 INFO - ##### 06:20:20 INFO - ##### Running read-buildbot-config step. 06:20:20 INFO - ##### 06:20:20 INFO - Running pre-action listener: _resource_record_pre_action 06:20:20 INFO - Running main action method: read_buildbot_config 06:20:20 INFO - Using buildbot properties: 06:20:20 INFO - { 06:20:20 INFO - "project": "", 06:20:20 INFO - "product": "firefox", 06:20:20 INFO - "script_repo_revision": "production", 06:20:20 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 06:20:20 INFO - "repository": "", 06:20:20 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 06:20:20 INFO - "buildid": "20160712050428", 06:20:20 INFO - "pgo_build": "False", 06:20:20 INFO - "basedir": "/builds/slave/test", 06:20:20 INFO - "buildnumber": 130, 06:20:20 INFO - "slavename": "talos-linux64-ix-045", 06:20:20 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:20:20 INFO - "platform": "ubuntu64_hw", 06:20:20 INFO - "scheduler": "tests-autoland-linux64-talos", 06:20:20 INFO - "branch": "autoland", 06:20:20 INFO - "repo_path": "integration/autoland", 06:20:20 INFO - "revision": "621f32c868bb5654a1ae2dc25cbb622b3c0efd69", 06:20:20 INFO - "stage_platform": "linux64", 06:20:20 INFO - "builduid": "0f091952f68949979462673a70043946", 06:20:20 INFO - "slavebuilddir": "test" 06:20:20 INFO - } 06:20:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 06:20:20 INFO - Running post-action listener: _resource_record_post_action 06:20:20 INFO - ##### 06:20:20 INFO - ##### Finished read-buildbot-config step (success) 06:20:20 INFO - ##### 06:20:20 INFO - ##### 06:20:20 INFO - ##### Running download-and-extract step. 06:20:20 INFO - ##### 06:20:20 INFO - Running pre-action listener: _resource_record_pre_action 06:20:20 INFO - Running main action method: download_and_extract 06:20:20 INFO - mkdir: /builds/slave/test/build/tests 06:20:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:20:20 INFO - https://queue.taskcluster.net/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:20:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 06:20:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 06:20:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/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 06:20:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/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 06:20:22 INFO - Downloaded 1361 bytes. 06:20:22 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 06:20:22 INFO - Using the following test package requirements: 06:20:22 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 06:20:22 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 06:20:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 06:20:22 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 06:20:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 06:20:22 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 06:20:22 INFO - u'jsshell-linux-x86_64.zip'], 06:20:22 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 06:20:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 06:20:22 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 06:20:22 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 06:20:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 06:20:22 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 06:20:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 06:20:22 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 06:20:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 06:20:22 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 06:20:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 06:20:22 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:20:22 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')]} 06:20:22 INFO - https://queue.taskcluster.net/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:20:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 06:20:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 06:20:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/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 06:20:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/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 06:20:23 INFO - Downloaded 22700742 bytes. 06:20:23 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'] 06:20:23 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 06:20:24 INFO - Return code: 0 06:20:24 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 06:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:20:24 INFO - https://queue.taskcluster.net/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:20:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 06:20:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 06:20:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/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 06:20:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/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 06:20:37 INFO - Downloaded 22700742 bytes. 06:20:37 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'] 06:20:37 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 06:20:38 INFO - Return code: 0 06:20:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:20:38 INFO - https://queue.taskcluster.net/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:20:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 06:20:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 06:20:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/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 06:20:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/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 06:20:40 INFO - Downloaded 14403317 bytes. 06:20:40 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'] 06:20:40 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 06:20:40 INFO - Return code: 0 06:20:40 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')]} 06:20:40 INFO - https://queue.taskcluster.net/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:20:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 06:20:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 06:20:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/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 06:20:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ImXuKDWDTaytHzOplERjYA/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 06:20:42 INFO - Downloaded 56656212 bytes. 06:20:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 06:20:42 INFO - mkdir: /builds/slave/test/properties 06:20:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:20:42 INFO - Writing to file /builds/slave/test/properties/build_url 06:20:42 INFO - Contents: 06:20:42 INFO - build_url:https://queue.taskcluster.net/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 06:20:42 INFO - Running post-action listener: _resource_record_post_action 06:20:42 INFO - Running post-action listener: set_extra_try_arguments 06:20:42 INFO - ##### 06:20:42 INFO - ##### Finished download-and-extract step (success) 06:20:42 INFO - ##### 06:20:42 INFO - ##### 06:20:42 INFO - ##### Running populate-webroot step. 06:20:42 INFO - ##### 06:20:42 INFO - Running pre-action listener: _resource_record_pre_action 06:20:42 INFO - Running main action method: populate_webroot 06:20:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:20:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:20:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:20:42 INFO - '--tppagecycles', 06:20:42 INFO - '7'], 06:20:42 INFO - 'tests': ['tsvgm']}}, 06:20:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:20:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:20:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:20:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:20:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:20:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:20:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:42 INFO - 'talos_options': ['--disable-e10s'], 06:20:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:20:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:20:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:42 INFO - 'talos_options': ['--disable-e10s'], 06:20:42 INFO - 'tests': ['damp', 'tps']}, 06:20:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:42 INFO - 'tests': ['damp', 'tps']}, 06:20:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:20:42 INFO - 'tests': ['dromaeo_dom']}, 06:20:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:20:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:20:42 INFO - 'tests': ['basic_compositor_video']}, 06:20:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:20:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:20:42 INFO - 'tests': ['a11yr', 06:20:42 INFO - 'ts_paint', 06:20:42 INFO - 'tpaint', 06:20:42 INFO - 'sessionrestore', 06:20:42 INFO - 'sessionrestore_no_auto_restore', 06:20:42 INFO - 'tabpaint']}, 06:20:42 INFO - 'other-e10s': {'tests': ['a11yr', 06:20:42 INFO - 'ts_paint', 06:20:42 INFO - 'tpaint', 06:20:42 INFO - 'sessionrestore', 06:20:42 INFO - 'sessionrestore_no_auto_restore', 06:20:42 INFO - 'tabpaint']}, 06:20:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:20:42 INFO - 'ts_paint', 06:20:42 INFO - 'tpaint', 06:20:42 INFO - 'sessionrestore', 06:20:42 INFO - 'sessionrestore_no_auto_restore', 06:20:42 INFO - 'tabpaint']}, 06:20:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:20:42 INFO - 'ts_paint', 06:20:42 INFO - 'tpaint', 06:20:42 INFO - 'sessionrestore', 06:20:42 INFO - 'sessionrestore_no_auto_restore', 06:20:42 INFO - 'tabpaint']}, 06:20:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:20:42 INFO - 'tests': ['a11yr', 06:20:42 INFO - 'ts_paint', 06:20:42 INFO - 'tpaint', 06:20:42 INFO - 'sessionrestore', 06:20:42 INFO - 'sessionrestore_no_auto_restore', 06:20:42 INFO - 'tabpaint']}, 06:20:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:20:42 INFO - 'tests': ['a11yr', 06:20:42 INFO - 'ts_paint', 06:20:42 INFO - 'tpaint', 06:20:42 INFO - 'sessionrestore', 06:20:42 INFO - 'sessionrestore_no_auto_restore', 06:20:42 INFO - 'tabpaint']}, 06:20:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:20:42 INFO - 'tests': ['tsvgx', 06:20:42 INFO - 'tsvgr_opacity', 06:20:42 INFO - 'tart', 06:20:42 INFO - 'tscrollx', 06:20:42 INFO - 'cart']}, 06:20:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:20:42 INFO - 'tsvgr_opacity', 06:20:42 INFO - 'tart', 06:20:42 INFO - 'tscrollx', 06:20:42 INFO - 'cart']}, 06:20:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:42 INFO - 'talos_options': ['--disable-e10s'], 06:20:42 INFO - 'tests': ['tp5o']}, 06:20:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:42 INFO - 'tests': ['tp5o']}, 06:20:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:42 INFO - 'talos_options': ['--disable-e10s', 06:20:42 INFO - '--xperf_path', 06:20:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:20:42 INFO - 'tests': ['tp5n']}, 06:20:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:42 INFO - 'talos_options': ['--xperf_path', 06:20:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:20:42 INFO - 'tests': ['tp5n']}}, 06:20:42 INFO - 'talos.zip': {'path': '', 06:20:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:20:42 INFO - Running post-action listener: _resource_record_post_action 06:20:42 INFO - ##### 06:20:42 INFO - ##### Finished populate-webroot step (success) 06:20:42 INFO - ##### 06:20:42 INFO - ##### 06:20:42 INFO - ##### Running create-virtualenv step. 06:20:42 INFO - ##### 06:20:42 INFO - Running pre-action listener: _resource_record_pre_action 06:20:42 INFO - Running main action method: create_virtualenv 06:20:42 INFO - Creating virtualenv /builds/slave/test/build/venv 06:20:42 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 06:20:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:20:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:20:43 INFO - Using real prefix '/usr' 06:20:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:20:43 INFO - Installing distribute.............................................................................................................................................................................................done. 06:20:45 INFO - Installing pip.................done. 06:20:45 INFO - Return code: 0 06:20:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:20:45 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')]} 06:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:45 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')]} 06:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:45 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 0x2353030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26123b0>, '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 0x26132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2613740>, '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': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', '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 06:20:45 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 06:20:45 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 06:20:45 INFO - Using env: {'DISPLAY': ':0', 06:20:45 INFO - 'HOME': '/home/cltbld', 06:20:45 INFO - 'LANG': 'en_US.UTF-8', 06:20:45 INFO - 'LANGUAGE': 'en_US:en', 06:20:45 INFO - 'LOGNAME': 'cltbld', 06:20:45 INFO - 'MAIL': '/var/mail/cltbld', 06:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:45 INFO - 'MOZ_NO_REMOTE': '1', 06:20:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:45 INFO - 'NO_EM_RESTART': '1', 06:20:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:45 INFO - 'PWD': '/builds/slave/test', 06:20:45 INFO - 'SHELL': '/bin/bash', 06:20:45 INFO - 'SHLVL': '1', 06:20:45 INFO - 'TERM': 'linux', 06:20:45 INFO - 'TMOUT': '86400', 06:20:45 INFO - 'USER': 'cltbld', 06:20:45 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', 06:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:45 INFO - '_': '/tools/buildbot/bin/python'} 06:20:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:45 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 06:20:45 INFO - Cleaning up... 06:20:45 INFO - Return code: 0 06:20:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:20:45 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')]} 06:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:45 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')]} 06:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:45 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 0x2353030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26123b0>, '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 0x26132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2613740>, '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': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', '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 06:20:45 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 06:20:45 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 06:20:45 INFO - Using env: {'DISPLAY': ':0', 06:20:45 INFO - 'HOME': '/home/cltbld', 06:20:45 INFO - 'LANG': 'en_US.UTF-8', 06:20:45 INFO - 'LANGUAGE': 'en_US:en', 06:20:45 INFO - 'LOGNAME': 'cltbld', 06:20:45 INFO - 'MAIL': '/var/mail/cltbld', 06:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:45 INFO - 'MOZ_NO_REMOTE': '1', 06:20:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:45 INFO - 'NO_EM_RESTART': '1', 06:20:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:45 INFO - 'PWD': '/builds/slave/test', 06:20:45 INFO - 'SHELL': '/bin/bash', 06:20:45 INFO - 'SHLVL': '1', 06:20:45 INFO - 'TERM': 'linux', 06:20:45 INFO - 'TMOUT': '86400', 06:20:45 INFO - 'USER': 'cltbld', 06:20:45 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', 06:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:45 INFO - '_': '/tools/buildbot/bin/python'} 06:20:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:45 INFO - Downloading/unpacking psutil>=3.1.1 06:20:45 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 06:20:45 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 06:20:45 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 06:20:45 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 06:20:45 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 06:20:45 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 06:20:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:20:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:20:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:20:47 INFO - Installing collected packages: psutil 06:20:47 INFO - Running setup.py install for psutil 06:20:48 INFO - building 'psutil._psutil_linux' extension 06:20:48 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 06:20:48 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 06:20:48 INFO - building 'psutil._psutil_posix' extension 06:20:48 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 06:20:48 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 06:20:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:20:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:20:48 INFO - Successfully installed psutil 06:20:48 INFO - Cleaning up... 06:20:48 INFO - Return code: 0 06:20:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:20:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2353030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26123b0>, '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 0x26132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2613740>, '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': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', '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 06:20:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:20:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:20:48 INFO - Using env: {'DISPLAY': ':0', 06:20:48 INFO - 'HOME': '/home/cltbld', 06:20:48 INFO - 'LANG': 'en_US.UTF-8', 06:20:48 INFO - 'LANGUAGE': 'en_US:en', 06:20:48 INFO - 'LOGNAME': 'cltbld', 06:20:48 INFO - 'MAIL': '/var/mail/cltbld', 06:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:48 INFO - 'MOZ_NO_REMOTE': '1', 06:20:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:48 INFO - 'NO_EM_RESTART': '1', 06:20:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:48 INFO - 'PWD': '/builds/slave/test', 06:20:48 INFO - 'SHELL': '/bin/bash', 06:20:48 INFO - 'SHLVL': '1', 06:20:48 INFO - 'TERM': 'linux', 06:20:48 INFO - 'TMOUT': '86400', 06:20:48 INFO - 'USER': 'cltbld', 06:20:48 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', 06:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:48 INFO - '_': '/tools/buildbot/bin/python'} 06:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:20:48 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 06:20:48 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 06:20:48 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 06:20:48 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 06:20:48 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 06:20:48 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 06:20:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:20:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:20:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:20:50 INFO - Installing collected packages: mozsystemmonitor 06:20:50 INFO - Running setup.py install for mozsystemmonitor 06:20:50 INFO - Successfully installed mozsystemmonitor 06:20:50 INFO - Cleaning up... 06:20:50 INFO - Return code: 0 06:20:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:20:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:20:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2353030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26123b0>, '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 0x26132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2613740>, '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': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', '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 06:20:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:20:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:20:50 INFO - Using env: {'DISPLAY': ':0', 06:20:50 INFO - 'HOME': '/home/cltbld', 06:20:50 INFO - 'LANG': 'en_US.UTF-8', 06:20:50 INFO - 'LANGUAGE': 'en_US:en', 06:20:50 INFO - 'LOGNAME': 'cltbld', 06:20:50 INFO - 'MAIL': '/var/mail/cltbld', 06:20:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:50 INFO - 'MOZ_NO_REMOTE': '1', 06:20:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:50 INFO - 'NO_EM_RESTART': '1', 06:20:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:50 INFO - 'PWD': '/builds/slave/test', 06:20:50 INFO - 'SHELL': '/bin/bash', 06:20:50 INFO - 'SHLVL': '1', 06:20:50 INFO - 'TERM': 'linux', 06:20:50 INFO - 'TMOUT': '86400', 06:20:50 INFO - 'USER': 'cltbld', 06:20:50 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', 06:20:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:50 INFO - '_': '/tools/buildbot/bin/python'} 06:20:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:50 INFO - Downloading/unpacking jsonschema==2.5.1 06:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:20:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:53 INFO - Downloading functools32-3.2.3-2.tar.gz 06:20:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:20:53 INFO - warning: no files found matching '*.txt' 06:20:53 INFO - no previously-included directories found matching 'build' 06:20:53 INFO - no previously-included directories found matching 'dist' 06:20:53 INFO - no previously-included directories found matching '.git*' 06:20:53 INFO - Installing collected packages: jsonschema, functools32 06:20:53 INFO - Running setup.py install for functools32 06:20:53 INFO - warning: no files found matching '*.txt' 06:20:53 INFO - no previously-included directories found matching 'build' 06:20:53 INFO - no previously-included directories found matching 'dist' 06:20:53 INFO - no previously-included directories found matching '.git*' 06:20:53 INFO - Successfully installed jsonschema functools32 06:20:53 INFO - Cleaning up... 06:20:53 INFO - Return code: 0 06:20:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:20:53 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')]} 06:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:53 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')]} 06:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:53 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 0x2353030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26123b0>, '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 0x26132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2613740>, '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': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', '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 06:20:53 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 06:20:53 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 06:20:53 INFO - Using env: {'DISPLAY': ':0', 06:20:53 INFO - 'HOME': '/home/cltbld', 06:20:53 INFO - 'LANG': 'en_US.UTF-8', 06:20:53 INFO - 'LANGUAGE': 'en_US:en', 06:20:53 INFO - 'LOGNAME': 'cltbld', 06:20:53 INFO - 'MAIL': '/var/mail/cltbld', 06:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:53 INFO - 'MOZ_NO_REMOTE': '1', 06:20:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:53 INFO - 'NO_EM_RESTART': '1', 06:20:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:53 INFO - 'PWD': '/builds/slave/test', 06:20:53 INFO - 'SHELL': '/bin/bash', 06:20:53 INFO - 'SHLVL': '1', 06:20:53 INFO - 'TERM': 'linux', 06:20:53 INFO - 'TMOUT': '86400', 06:20:53 INFO - 'USER': 'cltbld', 06:20:53 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', 06:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:53 INFO - '_': '/tools/buildbot/bin/python'} 06:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:53 INFO - Downloading/unpacking blobuploader==1.2.4 06:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:55 INFO - Downloading blobuploader-1.2.4.tar.gz 06:20:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:20:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:20:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:56 INFO - Downloading docopt-0.6.1.tar.gz 06:20:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:20:56 INFO - Installing collected packages: blobuploader, requests, docopt 06:20:56 INFO - Running setup.py install for blobuploader 06:20:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:20:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:20:56 INFO - Running setup.py install for requests 06:20:56 INFO - Running setup.py install for docopt 06:20:56 INFO - Successfully installed blobuploader requests docopt 06:20:56 INFO - Cleaning up... 06:20:56 INFO - Return code: 0 06:20:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:20:56 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')]} 06:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:56 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')]} 06:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:56 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 0x2353030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26123b0>, '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 0x26132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2613740>, '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': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', '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 06:20:56 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 06:20:56 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 06:20:56 INFO - Using env: {'DISPLAY': ':0', 06:20:56 INFO - 'HOME': '/home/cltbld', 06:20:56 INFO - 'LANG': 'en_US.UTF-8', 06:20:56 INFO - 'LANGUAGE': 'en_US:en', 06:20:56 INFO - 'LOGNAME': 'cltbld', 06:20:56 INFO - 'MAIL': '/var/mail/cltbld', 06:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:56 INFO - 'MOZ_NO_REMOTE': '1', 06:20:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:56 INFO - 'NO_EM_RESTART': '1', 06:20:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:56 INFO - 'PWD': '/builds/slave/test', 06:20:56 INFO - 'SHELL': '/bin/bash', 06:20:56 INFO - 'SHLVL': '1', 06:20:56 INFO - 'TERM': 'linux', 06:20:56 INFO - 'TMOUT': '86400', 06:20:56 INFO - 'USER': 'cltbld', 06:20:56 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', 06:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:56 INFO - '_': '/tools/buildbot/bin/python'} 06:20:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:20:57 INFO - Running setup.py (path:/tmp/pip-rsfdZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:20:57 INFO - Running setup.py (path:/tmp/pip-fvV8Wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:20:57 INFO - Running setup.py (path:/tmp/pip-8y0cp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:20:57 INFO - Running setup.py (path:/tmp/pip-n97VzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:20:57 INFO - Running setup.py (path:/tmp/pip-reY3Zz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:20:57 INFO - Running setup.py (path:/tmp/pip-Z_szKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:20:57 INFO - Running setup.py (path:/tmp/pip-xUi1wK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:20:57 INFO - Running setup.py (path:/tmp/pip-hwuFaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:20:57 INFO - Running setup.py (path:/tmp/pip-arvQXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:20:57 INFO - Running setup.py (path:/tmp/pip-D5dJrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:20:57 INFO - Running setup.py (path:/tmp/pip-xCRAYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:20:57 INFO - Running setup.py (path:/tmp/pip-b3GnWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:20:57 INFO - Running setup.py (path:/tmp/pip-YrScTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:20:57 INFO - Running setup.py (path:/tmp/pip-DV7nAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:20:58 INFO - Running setup.py (path:/tmp/pip-icEmaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:20:58 INFO - Running setup.py (path:/tmp/pip-iSB6bP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:20:58 INFO - Running setup.py (path:/tmp/pip-H22B_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:20:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:20:58 INFO - Running setup.py install for manifestparser 06:20:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:20:58 INFO - Running setup.py install for mozcrash 06:20:58 INFO - Running setup.py install for mozdebug 06:20:58 INFO - Running setup.py install for mozdevice 06:20:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:20:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:20:58 INFO - Running setup.py install for mozfile 06:20:58 INFO - Running setup.py install for mozhttpd 06:20:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:20:58 INFO - Running setup.py install for mozinfo 06:20:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:20:58 INFO - Running setup.py install for mozInstall 06:20:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:20:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:20:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:20:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:20:59 INFO - Running setup.py install for mozleak 06:20:59 INFO - Running setup.py install for mozlog 06:20:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:20:59 INFO - Running setup.py install for moznetwork 06:20:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:20:59 INFO - Running setup.py install for mozprocess 06:20:59 INFO - Running setup.py install for mozprofile 06:20:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:20:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:20:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:20:59 INFO - Running setup.py install for mozrunner 06:20:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:20:59 INFO - Running setup.py install for mozscreenshot 06:20:59 INFO - Running setup.py install for moztest 06:20:59 INFO - Running setup.py install for mozversion 06:21:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:21:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:21:00 INFO - Cleaning up... 06:21:00 INFO - Return code: 0 06:21:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:21: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')]} 06:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21: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')]} 06:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:00 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 0x2353030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26123b0>, '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 0x26132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2613740>, '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': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', '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 06:21:00 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 06:21:00 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 06:21:00 INFO - Using env: {'DISPLAY': ':0', 06:21:00 INFO - 'HOME': '/home/cltbld', 06:21:00 INFO - 'LANG': 'en_US.UTF-8', 06:21:00 INFO - 'LANGUAGE': 'en_US:en', 06:21:00 INFO - 'LOGNAME': 'cltbld', 06:21:00 INFO - 'MAIL': '/var/mail/cltbld', 06:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:00 INFO - 'MOZ_NO_REMOTE': '1', 06:21:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:21:00 INFO - 'NO_EM_RESTART': '1', 06:21:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:00 INFO - 'PWD': '/builds/slave/test', 06:21:00 INFO - 'SHELL': '/bin/bash', 06:21:00 INFO - 'SHLVL': '1', 06:21:00 INFO - 'TERM': 'linux', 06:21:00 INFO - 'TMOUT': '86400', 06:21:00 INFO - 'USER': 'cltbld', 06:21:00 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', 06:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:00 INFO - '_': '/tools/buildbot/bin/python'} 06:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:21:00 INFO - Running setup.py (path:/tmp/pip-UHHK92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:21:00 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)) 06:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:21:00 INFO - Running setup.py (path:/tmp/pip-kAv2D4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:21:00 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)) 06:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:21:00 INFO - Running setup.py (path:/tmp/pip-YAL9AW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:21:00 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)) 06:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:21:00 INFO - Running setup.py (path:/tmp/pip-LIXlhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:21:00 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)) 06:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:21:00 INFO - Running setup.py (path:/tmp/pip-CZnkZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:21:00 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)) 06:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:21:00 INFO - Running setup.py (path:/tmp/pip-ORXmCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:21:00 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)) 06:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:21:00 INFO - Running setup.py (path:/tmp/pip-H8hlt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:21:00 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)) 06:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:21:00 INFO - Running setup.py (path:/tmp/pip-XiMzGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:21:00 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)) 06:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:21:00 INFO - Running setup.py (path:/tmp/pip-YwR0Gw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:21:00 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)) 06:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:21:00 INFO - Running setup.py (path:/tmp/pip-f4Vxkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:21:01 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)) 06:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:21:01 INFO - Running setup.py (path:/tmp/pip-DbzOZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:21:01 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)) 06:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:21:01 INFO - Running setup.py (path:/tmp/pip-nxEiB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:21:01 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)) 06:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:21:01 INFO - Running setup.py (path:/tmp/pip-EQM_v4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:21:01 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)) 06:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:21:01 INFO - Running setup.py (path:/tmp/pip-9zMazo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:21:01 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)) 06:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:21:01 INFO - Running setup.py (path:/tmp/pip-aZXU1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:21:01 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)) 06:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:21:01 INFO - Running setup.py (path:/tmp/pip-1b3c3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:21:01 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)) 06:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:21:01 INFO - Running setup.py (path:/tmp/pip-4XSygl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:21:01 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)) 06:21:01 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)) 06:21:01 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)) 06:21:01 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)) 06:21:01 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)) 06:21:01 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)) 06:21:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:21:01 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 06:21:01 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 06:21:01 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 06:21:01 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 06:21:01 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 06:21:01 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 06:21:03 INFO - Downloading blessings-1.6.tar.gz 06:21:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:21:03 INFO - Installing collected packages: blessings 06:21:03 INFO - Running setup.py install for blessings 06:21:03 INFO - Successfully installed blessings 06:21:03 INFO - Cleaning up... 06:21:03 INFO - Return code: 0 06:21:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:21:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:21:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:21:03 INFO - Reading from file tmpfile_stdout 06:21:03 INFO - Current package versions: 06:21:03 INFO - argparse == 1.2.1 06:21:03 INFO - blessings == 1.6 06:21:03 INFO - blobuploader == 1.2.4 06:21:03 INFO - docopt == 0.6.1 06:21:03 INFO - functools32 == 3.2.3-2 06:21:03 INFO - jsonschema == 2.5.1 06:21:03 INFO - manifestparser == 1.1 06:21:03 INFO - mozInstall == 1.12 06:21:03 INFO - mozcrash == 0.17 06:21:03 INFO - mozdebug == 0.1 06:21:03 INFO - mozdevice == 0.48 06:21:03 INFO - mozfile == 1.2 06:21:03 INFO - mozhttpd == 0.7 06:21:03 INFO - mozinfo == 0.9 06:21:03 INFO - mozleak == 0.1 06:21:03 INFO - mozlog == 3.2 06:21:03 INFO - moznetwork == 0.27 06:21:03 INFO - mozprocess == 0.23 06:21:03 INFO - mozprofile == 0.28 06:21:03 INFO - mozrunner == 6.12 06:21:03 INFO - mozscreenshot == 0.1 06:21:03 INFO - mozsystemmonitor == 0.3 06:21:03 INFO - moztest == 0.7 06:21:03 INFO - mozversion == 1.4 06:21:03 INFO - psutil == 3.1.1 06:21:03 INFO - requests == 1.2.3 06:21:03 INFO - wsgiref == 0.1.2 06:21:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:21: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')]} 06:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21: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')]} 06:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:03 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 0x2353030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26123b0>, '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 0x26132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2613740>, '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': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', '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 06:21:03 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 06:21:03 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 06:21:03 INFO - Using env: {'DISPLAY': ':0', 06:21:03 INFO - 'HOME': '/home/cltbld', 06:21:03 INFO - 'LANG': 'en_US.UTF-8', 06:21:03 INFO - 'LANGUAGE': 'en_US:en', 06:21:03 INFO - 'LOGNAME': 'cltbld', 06:21:03 INFO - 'MAIL': '/var/mail/cltbld', 06:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:03 INFO - 'MOZ_NO_REMOTE': '1', 06:21:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:21:03 INFO - 'NO_EM_RESTART': '1', 06:21:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:03 INFO - 'PWD': '/builds/slave/test', 06:21:03 INFO - 'SHELL': '/bin/bash', 06:21:03 INFO - 'SHLVL': '1', 06:21:03 INFO - 'TERM': 'linux', 06:21:03 INFO - 'TMOUT': '86400', 06:21:03 INFO - 'USER': 'cltbld', 06:21:03 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', 06:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:03 INFO - '_': '/tools/buildbot/bin/python'} 06:21:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:04 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)) 06:21:04 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)) 06:21:04 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)) 06:21:04 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)) 06:21:04 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)) 06:21:04 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)) 06:21:04 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)) 06:21:04 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)) 06:21:04 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)) 06:21:04 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)) 06:21:04 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)) 06:21:04 INFO - Cleaning up... 06:21:04 INFO - Return code: 0 06:21:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:21:04 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')]} 06:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:04 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')]} 06:21:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:04 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 0x2353030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26123b0>, '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 0x26132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2613740>, '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': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', '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 06:21:04 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 06:21:04 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 06:21:04 INFO - Using env: {'DISPLAY': ':0', 06:21:04 INFO - 'HOME': '/home/cltbld', 06:21:04 INFO - 'LANG': 'en_US.UTF-8', 06:21:04 INFO - 'LANGUAGE': 'en_US:en', 06:21:04 INFO - 'LOGNAME': 'cltbld', 06:21:04 INFO - 'MAIL': '/var/mail/cltbld', 06:21:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:04 INFO - 'MOZ_NO_REMOTE': '1', 06:21:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:21:04 INFO - 'NO_EM_RESTART': '1', 06:21:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:21:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:04 INFO - 'PWD': '/builds/slave/test', 06:21:04 INFO - 'SHELL': '/bin/bash', 06:21:04 INFO - 'SHLVL': '1', 06:21:04 INFO - 'TERM': 'linux', 06:21:04 INFO - 'TMOUT': '86400', 06:21:04 INFO - 'USER': 'cltbld', 06:21:04 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', 06:21:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:04 INFO - '_': '/tools/buildbot/bin/python'} 06:21:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:21:04 INFO - Cleaning up... 06:21:04 INFO - Return code: 0 06:21:04 INFO - Running post-action listener: _resource_record_post_action 06:21:04 INFO - Running post-action listener: _start_resource_monitoring 06:21:04 INFO - Starting resource monitoring. 06:21:04 INFO - ##### 06:21:04 INFO - ##### Finished create-virtualenv step (success) 06:21:04 INFO - ##### 06:21:04 INFO - ##### 06:21:04 INFO - ##### Running install step. 06:21:04 INFO - ##### 06:21:04 INFO - Running pre-action listener: _resource_record_pre_action 06:21:04 INFO - Running main action method: install 06:21:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:21:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:21:04 INFO - Reading from file tmpfile_stdout 06:21:04 INFO - Detecting whether we're running mozinstall >=1.0... 06:21:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:21:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:21:04 INFO - Reading from file tmpfile_stdout 06:21:04 INFO - Output received: 06:21:04 INFO - Usage: mozinstall [options] installer 06:21:04 INFO - Options: 06:21:04 INFO - -h, --help show this help message and exit 06:21:04 INFO - -d DEST, --destination=DEST 06:21:04 INFO - Directory to install application into. [default: 06:21:04 INFO - "/builds/slave/test"] 06:21:04 INFO - --app=APP Application being installed. [default: firefox] 06:21:04 INFO - mkdir: /builds/slave/test/build/application 06:21:04 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'] 06:21:04 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 06:21:20 INFO - Reading from file tmpfile_stdout 06:21:20 INFO - Output received: 06:21:20 INFO - /builds/slave/test/build/application/firefox/firefox 06:21:20 INFO - Running post-action listener: _resource_record_post_action 06:21:20 INFO - ##### 06:21:20 INFO - ##### Finished install step (success) 06:21:20 INFO - ##### 06:21:20 INFO - ##### 06:21:20 INFO - ##### Running run-tests step. 06:21:20 INFO - ##### 06:21:20 INFO - Running pre-action listener: _resource_record_pre_action 06:21:20 INFO - Running main action method: run_tests 06:21:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:21:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:21:20 INFO - Python 2.7.3 06:21:20 INFO - Return code: 0 06:21:20 INFO - grabbing minidump binary from tooltool 06:21:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:21:20 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 0x26123b0>, '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 0x26132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2613740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:21:20 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 06:21:20 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 06:21:20 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 06:21:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:21:21 INFO - Return code: 0 06:21:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:21:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:21:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:21:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:21:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:21:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 06:21:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 06:21:21 INFO - Using env: {'DISPLAY': ':0', 06:21:21 INFO - 'HOME': '/home/cltbld', 06:21:21 INFO - 'LANG': 'en_US.UTF-8', 06:21:21 INFO - 'LANGUAGE': 'en_US:en', 06:21:21 INFO - 'LOGNAME': 'cltbld', 06:21:21 INFO - 'MAIL': '/var/mail/cltbld', 06:21:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:21:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:21:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:21 INFO - 'MOZ_NO_REMOTE': '1', 06:21:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:21:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:21:21 INFO - 'NO_EM_RESTART': '1', 06:21:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:21:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:21 INFO - 'PWD': '/builds/slave/test', 06:21:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:21:21 INFO - 'SHELL': '/bin/bash', 06:21:21 INFO - 'SHLVL': '1', 06:21:21 INFO - 'TERM': 'linux', 06:21:21 INFO - 'TMOUT': '86400', 06:21:21 INFO - 'USER': 'cltbld', 06:21:21 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1468329315.267517-1773959525', 06:21:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:21 INFO - '_': '/tools/buildbot/bin/python'} 06:21:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 06:21:21 INFO - mozversion application_buildid: 20160712050428 06:21:21 INFO - mozversion application_changeset: 621f32c868bb5654a1ae2dc25cbb622b3c0efd69 06:21:21 INFO - mozversion application_display_name: Nightly 06:21:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:21:21 INFO - mozversion application_name: Firefox 06:21:21 INFO - mozversion application_remotingname: firefox 06:21:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 06:21:21 INFO - mozversion application_vendor: Mozilla 06:21:21 INFO - mozversion application_version: 50.0a1 06:21:21 INFO - mozversion platform_buildid: 20160712050428 06:21:21 INFO - mozversion platform_changeset: 621f32c868bb5654a1ae2dc25cbb622b3c0efd69 06:21:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 06:21:21 INFO - mozversion platform_version: 50.0a1 06:21:21 INFO - using testdate: 1468329681 06:21:21 INFO - actual date: 1468329681 06:21:21 INFO - starting webserver on 'localhost:56326' 06:21:21 INFO - SUITE-START | Running 2 tests 06:21:21 INFO - TEST-START | dromaeo_css 06:21:21 INFO - operating with platform_type : linux_ 06:21:21 INFO - Initialising browser for dromaeo_css test... 06:21:21 INFO - TEST-INFO | started process 17664 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzBr9o6/profile http://localhost:56326/getInfo.html) 06:21:25 INFO - PROCESS | 17664 | __metrics Screen width/height:1600/1200 06:21:25 INFO - PROCESS | 17664 | colorDepth:24 06:21:25 INFO - PROCESS | 17664 | Browser inner width/height: 1024/697 06:21:25 INFO - PROCESS | 17664 | __metrics 06:21:26 INFO - PROCESS | 17664 | 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" 06:21:26 INFO - PROCESS | 17664 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:21:27 INFO - TEST-INFO | 17664: exit 0 06:21:27 INFO - Browser initialized. 06:21:27 INFO - Running cycle 1/1 for dromaeo_css test... 06:21:27 INFO - TEST-INFO | started process 17744 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzBr9o6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:21:39 INFO - PROCESS | 17744 | RSS: Main: 158752768 06:21:39 INFO - PROCESS | 17744 | 06:22:31 INFO - PROCESS | 17744 | 1468329751007 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] 06:24:42 INFO - PROCESS | 17744 | Cycle 1(1): loaded http://localhost:56326/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56326/tests/dromaeo/cssquery-ext.html) 06:24:44 INFO - PROCESS | 17744 | RSS: Main: 258125824 06:24:44 INFO - PROCESS | 17744 | 06:27:46 INFO - PROCESS | 17744 | Cycle 1(1): loaded http://localhost:56326/tests/dromaeo/cssquery-ext.html (next: http://localhost:56326/tests/dromaeo/cssquery-jquery.html) 06:27:47 INFO - PROCESS | 17744 | RSS: Main: 243843072 06:27:47 INFO - PROCESS | 17744 | 06:30:49 INFO - PROCESS | 17744 | Cycle 1(1): loaded http://localhost:56326/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56326/tests/dromaeo/cssquery-mootools.html) 06:30:50 INFO - PROCESS | 17744 | RSS: Main: 246108160 06:30:50 INFO - PROCESS | 17744 | 06:33:52 INFO - PROCESS | 17744 | Cycle 1(1): loaded http://localhost:56326/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56326/tests/dromaeo/cssquery-prototype.html) 06:33:52 INFO - PROCESS | 17744 | RSS: Main: 239079424 06:33:52 INFO - PROCESS | 17744 | 06:36:55 INFO - PROCESS | 17744 | Cycle 1(1): loaded http://localhost:56326/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56326/tests/dromaeo/cssquery-yui.html) 06:36:56 INFO - PROCESS | 17744 | RSS: Main: 211591168 06:36:56 INFO - PROCESS | 17744 | 06:39:59 INFO - PROCESS | 17744 | Cycle 1(1): loaded http://localhost:56326/tests/dromaeo/cssquery-yui.html (next: http://localhost:56326/tests/dromaeo/cssquery-dojo.html) 06:39:59 INFO - PROCESS | 17744 | RSS: Main: 280428544 06:39:59 INFO - PROCESS | 17744 | 06:39:59 INFO - PROCESS | 17744 | __start_tp_report 06:39:59 INFO - PROCESS | 17744 | _x_x_mozilla_page_load 06:39:59 INFO - PROCESS | 17744 | _x_x_mozilla_page_load_details 06:39:59 INFO - PROCESS | 17744 | |i|pagename|runs| 06:39:59 INFO - PROCESS | 17744 | |0;dojo.html;2555.667763190785;2609.5694210455276;2651.6420283261755;2662.9600555991665;2664.586989016702;4490.416245888038;4516.390287311212;4638.373819534362;4651.581357677808;4666.113438446697;4497.3029180477;4547.567981041804;4575.405197324343;4585.082983403325;4598.97700511497;4150.64719498843;4223.176480586287;4225.598568136037;4237.6186143247105;4272.743635381871;4249.51130619978;4292.334688123346;4299.785010749466;4335.739855608658;4340.327249276367;4252.681048921343;4292.61366477017;4312.22379971605;4334.133173365324;4354.08564201518;4184.14225083857;4209.557996410364;4214.683898707579;4250.659947204216;4269.444972153631;4226.725262857904;4243.702940794145;4259.2759230930815;4262.722923009995;4329.610335069828;4641.675082744209;4658.231391820345;4700.482946875841;4706.270528068155;4708.058388322349;4803.351547541107;4913.31213630092;4963.3299504566785;4973.055119527315;5021.322121513622;5001.574866136383;5045.974770126126;5049.5455409013;5057.949420505822;5060.493950604947;4676.859694209179;4705.623550116016;4710.105080034782;4719.38647975761;4721.504242054603;4843.2735089736825;4966.8758281043265;4969.676970996874;5155.123628983082;5162.6902385856965;4794.880127996828;4833.806647734122;4836.830710925101;4856.2958371036;4858.82994095205;4883.633727470383;4901.019796040806;4923.335349727812;4931.778069986859;4946.109700253989;4718.05638872227;4822.517748225149;4858.149823780825;4908.828191013297;4927.630427717935;4874.902501949941;4883.609311255091;4894.510548945077;4907.877303067452;4948.604111671058;4184.811683474251;4185.22573323936;4190.958090419118;4211.557786432441;4246.681498887596;3584.8386822593047;3627.2926779278314;3645.489631451599;3696.5748938871834;3730.6828919542145;4327.82688692455;4339.913201736011;4347.130573885235;4347.956520434818;4393.6704747144095;4980.252962055648;4983.202687570044;4983.551480366785;4988.052270068748;5027.547520723152;4223.831046758157;4302.913941721148;4357.673174511924;4359.17175736609;4368.628666563378;4450.710703804242;4480.395146655225;4502.414686090853;4509.954900450954;4541.069080838421;4632.119897219518;4802.183628783185;4807.95192048075;4811.759412029314;4841.007593443335;4873.390826146731;4941.184704523714;4945.480724524017;4950.034743225036;4954.826581069645;4727.030958653537;4746.098241334135;4755.334253204485;4755.548222918817;4779.904401911873;4696.694714843524;4863.805447782049;4868.123737727243;4880.023995201045;4923.950760492349;4976.527229913152;4982.900341993212;5001.7749201285305;5003.624728145331;5041.596672266283;4700.858974230779;4719.551642593948;4734.692245004064;4738.644601654959;4782.163121453801;4495.640348772025;4581.198290299251;4652.976735116303;4738.241881298975;4769.165396055746;4599.218132917479;4634.119517291704;4634.76826158684;4637.188492013952;4655.18534256497;4755.072760811747;4760.190767569452;4766.165920963748;4806.327114203944;4810.4467986182035;4437.33439984005;4446.599806017474;4555.544445555418;4622.237330840374;4647.047355292225 06:39:59 INFO - PROCESS | 17744 | |1;ext.html;568.2470726287668;570.375438894411;578.057765841678;588.8204097750187;589.4900910712233;27824.304392390175;41374.17251654969;42571.78714106433;42673.7866310668;45218;41031;41295.17409651805;41386.58613413865;41418.17163656725;41429.7928510357;7603.80990475237;7884.974953256069;7997.320227780646;8005.959970200141;8023.518588884685;7630.42771792115;7796.220377962215;7799.727009554638;10089;10400.063994240516;10378.844317335246;10429.3220940639;10437.478126093665;10442.530086146104;10445.216608754336;10298.279120461571;10319.638812641522;10334.22493312997;10334.431606261653;10347.430891300975;7483.625818709097;7707.306342429209;7707.884381734279;9757.170640495566;10135.44255065971;39534.60465395338;39587.208255834725;39878;39905.800470997456;39993;2765.2119146043283;3049.8932537361306;3065.7240848323536;3091.7526597872115;3483.651634836521;30003.249918752204;30311;30438.54342184869;30825.229369265948;32033.679663203537;3490.982545087258;3496.3531746626954;3518.1732292911147;3527.206378564828;3591.964080359189;285215;286716;287714;289102.55448722834;289251;53698.731506343;54522.727386362814;54523;54588;54656.72671636617;58264.4173558259;58691.77195254302;58759;58804.41195588075;58931.70534147302;13768.724625507633;14332.713345733027;14376.424943002163;14384.28078596066;14393.92803035999;10034.147097496794;10166.847497287547;10171.338862973884;10172.033656802592;10210.591576336863;18127.371531127912;18244.90877545604;18357.541061473305;18609.90695046516;18617.90691046536;6706.698198581075;6721.764738234138;6896.137982752156;6941.245247005817;6949.687264073129;801.7234054251275;873.0861072521553;892.2505095719627;893.584483215308;954.398728800851;27042.864785675945;27669.584956225674;28466.857665711537;28493.145205643865;28516.572251416244;957.4255446731905;979.329159525713;991.5141580625586;1009.3086235928413;1018.2007124407305;14669.77995330071;15244.847551524565;15287.617809554627;15443.30505127272;15548.067115973077;395154;396682;398566;398998;399224;33466.49800252949;33784.66215337815;49282.50717492779;49473;49477.75261123671;1081.9242653014214;1117.5027112934665;1131.2646779593001;1193.7015746063485;1202.3567391445536;28363.432731344845;29284.414311714074;29287.12138635844;29656.703432966257;30140;41620.58379416167;41882.58117418787;41907.161856763305;41951.160976780906;42003;293998;295202;295949;296315;296878;21730;22133;23577.882110589337;27446.5883011751;27448.313792154557;41604;41821.372679410444;41905.79047104745;42765.57234427616;42896;36497.635023649425;36947.261054779294;36986.44520332143;37462.43806342962;37573.62426375701;35940;36367.27265454623;36618.26763464769;36901.81549092345;36937.630623694495;35897.28205435929;35992.63958257898;36087;36422;36732.44901326429;31742.36515269728;32620.496301598778;33109.503357450136;33181.83409082939;33208.667913320554;25204.49591008133;25300;25368;25482.050282206288;25668.486630267664 06:39:59 INFO - PROCESS | 17744 | |2;jquery.html;361.246800421122;373.3298728781838;374.89315545069644;382.6881091910091;389.0216106492173;16678.499645010637;18277.36029238977;18339.17473713683;19004.61990760184;19042.047897605116;17516.299348026063;17867.141257398787;18244.452666419995;18251.72622410665;18348;5110.335656364677;5272.367315922075;5289.841304760844;5319.175527793198;5333.413324534298;5214.608904332172;5347.465253474641;5676.829695109132;5682.26130603021;5785.884282314351;5711.628744131618;5732.7133643317875;5741.598088133832;5745.626534275259;5758.193852860602;5525.475079867406;5711.657300561979;5718.1136923776885;5718.971405142989;5719.799807006755;4844.757762111915;5496.560275177976;5505.311836020497;5512.283403157604;5513.696746678931;12479.126461147724;13185.802212966813;13210.537631182959;13210.66973325665;13510.527131550447;1862.7857796353321;1867.2997625890291;1874.5407375193029;1889.0649128681391;1893.6970084786506;9019.331423686574;9140.085991400872;9263.120003599648;9310.627574896997;9621.037896210393;1715.476779582227;1716.7253239481622;1716.8712346574057;1724.6981778188767;1782.4919897829186;67798.66100669514;72088.6395568024;72416.6379168106;73910.63044684796;74809;10216.182705383551;10229.335093218917;10414.531346089445;10454.790904181873;11216.439178041228;14339.928300358431;14461.855381446261;14464.927675361767;14482.710345793024;14484.493042743665;6970.059042029363;7196.568205907663;7208.171060328129;7233.31283528064;7316.158641756266;6822.3859852613505;6879.58722476653;6885.449164066863;6923.6192009439965;7012.439004879598;4486.2597671383855;4488.192125417456;4493.910121797546;4541.863744087683;4573.63410927125;1828.232142500153;1845.5570663040774;1867.8412334951681;1891.5649400637656;1898.924043038263;830.3647709502114;837.3719710217337;861.629499315288;872.1975782280317;952.2477242977969;17391.65216695659;17571.297148114187;17910.820891791176;18174.545636358933;18520.16659250337;841.022779361055;847.2163248994656;875.7941883657336;879.0286733159767;915.3135148638521;9342.53287335644;9705.902940970642;10093.343932644355;10197.541110650038;10278.948605256926;132488;300170;304896;307018;433389;50859;71653.64173179206;78366.60816695994;85896.57051714702;93184;890.8530092534788;904.9954550448996;909.9272058235325;923.422860712055;946.4321407155652;16533.91733041327;16575;16762.32950682008;16887.66224675524;17430.128493575525;26730.697454527202;28055.859720701264;40293;40492.39261411022;40731.59268407278;56386.718066409405;58046;58095.709521452125;58290.708546457;59366;7239.565626062453;7252.709891604488;7286.453515986428;7292.927070729224;7313.305236002571;6647.833804154935;9548.952255238679;11251.493682784458;11487.54049837997;11611.071114311004;9695.418274903528;9725.610975560898;9749.36629119105;9901.55443005052;10087.344322619007;9839.360441571274;9903.55434005457;9951.950240249042;10091.44497052669;10178;9531.38046027006;10076.395416274754;10097.5456104474;10142.483686216587;10507.842382364117;7457.291557302047;7467.2532746727065;7500.924990750241;7637.618119093912;7728.111267204119;8260.669573217245;9530.094641009093;9569.90430095718;9637;10227.130693891102 06:39:59 INFO - PROCESS | 17744 | |3;mootools.html;1236.264422668512;1349.102846607006;1394.7907813827924;1451.789490523874;1663.2265996311721;6307.148534947781;7112.466565007626;7377.225391333913;7388.076490438689;7555.811104722385;7268.527545709539;7405.407567394609;7413.258674132598;7417.851642967137;7421.5547067176;2170.175333373316;2192.572448372568;2194.8244140468723;2195.659672750718;2197.9780202198012;2138.5936672032262;2185.595664802017;2189.157174487818;2190.080166330137;2193.056985496236;2161.200355868323;2175.923842665507;2188.792064753854;2194.122351059581;2194.9780502195013;2123.50097727034;2123.5115923337567;2133.4026472587702;2134.8078672919432;2137.551114266006;2054.075665950328;2089.9268525601533;2090.383336915598;2091.466675997626;2094.518260800009;7283.053203083565;7302.926970730277;7400.222976587488;7405.407567394649;7489.887651685229;4449.866504004885;4548.044910568785;4552.4764652065105;4554.066416384666;4563;2693.62289279501;2757.2968892932377;2781.3185769486604;2799.14626039058;2808.2979255186206;834.5701963488748;839.6431516605479;846.4963346808639;851.1148405658108;856.2379482260793;9997.800043999152;10091.29360944734;10104.444255565868;10117.241206909437;10123.848142277871;3849;3963.6432721054666;4077.734947228421;4122.154958233555;4126.8761937141935;7874.05511338643;7932.603369831485;7938.801529961682;7942.205779422012;7944.6822127114265;4507.909841803145;4517.119161763425;4519.276915693474;4522.118186953512;4524.5701658342405;3695.0762309422644;3912.158885839558;4054.655354294859;4235.7670328131635;4236.978815105905;3341.766076374679;3380.763346565766;3426.623071462137;3450.9482357764655;3539.734519911017;1014.6144465103214;1018.6740243122277;1031.8245898197324;1033.1424917318607;1033.178622994706;650.2554575011585;711.7900219435279;723.7286017743346;739.1942782367246;803.999021218575;5073.543381095646;5086.389633243961;5164.147915593885;5208.687478751287;5255.5532779713385;432.4594257178527;447.7291238800544;449.95950364467353;451.90058187198764;606.7924829589086;3537.4163263061305;3711.9072023199637;3731.2350968051483;3750.437434384864;3758.830852611639;8576.228139467477;8881.067487913806;8993;9002.594883230271;9019.143181397756;8916.242119419792;8918.331125165638;8924.821503569892;8925.955370223108;8928.419652722552;6861.313868613199;6945.2707465715375;6950.20072691647;6952.409045231212;6954.478414118961;4988.850334489971;5035.773390197523;5061.822836200715;5109.565686916654;5147.510986456281;8349.874751878848;8376.036755773164;8413.831723365622;8437.789055273668;8452.281556067557;7837.686492540234;8117.350611950912;8145.592720364077;8344.290735287568;8494.957525212334;5683.374828768915;6250.249970003629;6456.354364563506;6488.837779055561;6585.341465853377;7273.490855640266;7281.854362912818;7339.522931009467;7349.853002940018;7358.080239970004;6268.310485846644;6558.704858281484;6564.113844630913;6654.5341826073645;6666.799995999933;6392.20097487814;6463.25672547663;6475;6604.933950660624;6647.135872336566;5602.971985140048;5663.32040155184;5710.5717071219;5715.31416230055;5733.569982251432;4204.768737719332;4369.410129632459;4383.123375324884;4393.670474714345;4456.866294011184;4401.537838526909;4442.822287108479;4557.635389168925;4591.196540605448;4595.9310610341545 06:39:59 INFO - PROCESS | 17744 | |4;prototype.html;301.4408272654226;306.40557318801524;308.8841684368362;308.94284557356883;310.07906517642596;4829.251466022772;4839.806407743686;4842.370491836061;4869.513048695128;4887.853364399065;4794.161021821176;4796.328514008042;4816.3016362627395;4821.662483626147;4823.252395878644;3616.9095772605633;3656.159083410817;3726.6087060858627;3740.56983446903;3797.841658294219;3774.792386418746;3786.413105968579;3797.2025874566375;3828.25349056934;3832.176082142339;3603.2433189030203;3742.120601658608;3757.229767897574;3767.943480847776;3778.263238668461;3538.327717733622;3698.981505092471;3714.7213958953184;3715;3756.69946404287;3307.1236122427335;3312.734981201498;3313.6355000949875;3327.15157634804;3366.5791776027995;4616.884577885546;4633.652476064309;4643;4648.628109751212;4663.440387153563;4219.852305169335;4227.788610569459;4249.938779691847;4257.1485702859245;4316.330968699856;4925.876853078666;4934.432540257881;4958.446400103913;4963.60291176705;4969.77636006377;3632.7093832493338;3637.672609465161;3643.4899114123778;3661.707063434919;3664.4320130379833;5026.974865125688;5164.999999999963;5211.941283193155;5236.240745091978;5245.167609490871;3675.4854320395166;3701.555813302421;3702.8148592570265;3705.3515634763808;3707.4994875691978;3758.8872333830027;3760.0599850037493;3761.605031471711;3763.849446022183;3766.39737641976;4254.893627659333;4272.33778764292;4279.957200428018;4281.721688090264;4286.828526858953;4776.952230477651;4975.776090075955;4983.950160498349;4984.152694042022;5005.224190250517;1306.9869301307056;1322.8544860065385;1330.2018788726687;1330.8089260112145;1336.6123824091098;839.9160083991552;865.7143142762873;879.2965627497977;881.6422709028153;882.1531329923217;4283.464566929134;4377.671674624416;4431.202383570988;4472.776361181928;4482.529334419839;5138.743062846843;5156.793728250828;5157.200633901752;5184.351956005499;5191.143461328838;4476.395686582269;4484.148011877733;4485.125400546927;4508.752018639007;4512.165249428866;2638.828476149044;2737.397772490049;2743.4101668141448;2746.6292050572915;2749.7112803155383;4910.238912968424;4911.0423467424225;4943.555080042814;4970.378702662168;4999.875028118717;5019.3976722793495;5272.55183309423;5278.703763862732;5311.884504254072;5322.973385133049;4901.632377571767;4917.254849070592;4923.8276660316715;4933.975330123399;4938.506149385104;5116.104681680766;5122.820701275437;5125.79496820138;5202.453742356998;5208.166693329201;4940.357753492023;5044.486654003711;5087.008033433445;5093.923591146062;5112.923306150336;4874.610031197567;4888.291197776373;4901.676547332129;4914.533119353656;4920;3699.1307042845447;3717.981410092932;3759.6804271637216;3770.45328427382;3779.433085037266;4986.551210391082;5200.167973124433;5209.322788037531;5209.635325527328;5238.319018527567;4774.785134669018;4809.547130963164;4830.985493046359;4837.612990960644;4854.271859221004;4854.611631069577;4856.125897338513;4864.318995340726;4926.187179115406;4936.802527898843;4501.482329532015;4728.834490792806;4735.952640473552;4743.881402964954;4752.7623618818225;4674.322223277673;4764.904701906012;4816.735079570657;4857.975710121426;4892.951070489392;4698;4752.405949256343;4753.382060332273;4755.215389460838;4776.259679749644 06:39:59 INFO - PROCESS | 17744 | |5;yui.html;658.3909883357894;682.0587589127007;693.1197379328256;746.7087835744061;748.7267147491164;11976.221545599541;12657.30384828834;12700.682482937931;12741.61775146746;12778.16941898776;12832.358382080905;12955.935220323909;12998.41507132179;13000;13816.240106794123;782.194339829976;803.333233416263;804.2319584796535;804.3042768005662;804.4409135650695;622.4024936061387;760.8592410404066;763.8854171874208;767.1024900866;789.0413148025149;822.4284122534841;823.736404350608;824.3858325547476;824.6577670266832;824.7525742277322;820.2494717333633;820.3970081989733;820.4420993724265;821.0270829067514;822.4982760516081;479.64266621367307;588.2911092133968;601.3475379213514;603.3996173807091;604.4650484321363;13338.133021353678;13359.198448093146;13367.665808354868;13402.262875541841;13415.195088294733;12337.568185113478;12610.74778504434;12795.296258705768;12812.61562153137;13192.86807131926;11917.702057448547;11924.344161071138;11987.58043468483;12262.754744905142;12611.180273282207;3592.3354179476883;3600.9756583204844;3627.310810945938;3711.6288371162937;3737.2151848111657;61851.69074154645;62204.68897655528;62252.68873655648;62919.6854015727;63062.68468657673;10039.378898685201;10187.739520734918;10294.948525257325;10336.534855931352;10648.946755266174;18427.81572184288;18493.5376615583;18512.352067677566;18531.258749649864;18544.443666690022;6821;6931.722731090801;7000.859982800417;7065.964670176616;7079.681414336367;3478.4086705260165;3667.02823751706;3783.4135708965155;3809.142942837839;3826.2921359548573;2548.1591074945595;2642.841429514235;2644.5504264274737;2649.45686134342;2650.2579277802247;461.9630429565627;475.5078493759012;476.56870532168443;478.6984199954076;482.99517004830204;460.49345719708725;508.1919747601258;524.9711265880337;563.1777604697188;567.3503838105346;7891.408144389194;8077.919220807834;8389.790255243668;8513.829723405497;8585.613647385882;571.5827445964469;583.6906439587026;586.5219845825638;586.8562202260473;593.7061154728351;3914.197589494147;3970.343921247772;3989.401589761501;4061.9796901015898;4073.837046518165;88671.11328886758;88714.55642721875;90639;91751;94182;49010.75494622504;49054;49625.75187124041;49703;50758.746206268734;58976;71351.2864871355;71560.64219678972;71938.2806171942;72858.63570682325;7673.808154796175;7687.730929417444;7747.418943579141;7771.378289736695;7866.9213307866185;44443.5556444353;45011.774941125084;45445.09109817851;45568.08863822771;46194.11901775379;61592;65716.34283657103;65720;66185;66509;9049.185573298435;9068.138526839939;9138.406003609743;9168.083191680778;9216.216621586971;9989.550470229002;10057.798844023226;10255.179585633281;10314.690559283234;10491.213159012952;8394.622241999003;9405.717828465156;9551.474668893277;9711;9838.36050656705;11582.594609188638;12212.694682633006;12412;12458.937705311415;12609.87390126087;12245.142840001114;12409.317487538272;12716.427760750974;12909.031822613153;12958.352082395653;10378;10745.731356716145;10751.19366047534;10803.567857285621;10849.783004340026;10615.522301496298;10901.469597544785;11091;11109.333439993627;11117.555297787998 06:39:59 INFO - PROCESS | 17744 | __end_tp_report 06:39:59 INFO - PROCESS | 17744 | __start_cc_report 06:39:59 INFO - PROCESS | 17744 | _x_x_mozilla_cycle_collect,942 06:39:59 INFO - PROCESS | 17744 | __end_cc_report 06:39:59 INFO - PROCESS | 17744 | __startTimestamp1468330799679__endTimestamp 06:39:59 INFO - PROCESS | 17744 | 06:39:59 INFO - PROCESS | 17744 | ------- Summary: start ------- 06:39:59 INFO - PROCESS | 17744 | Number of tests: 6 06:39:59 INFO - PROCESS | 17744 | 06:39:59 INFO - PROCESS | 17744 | [#0] dojo.html Cycles:165 Average:4565.73 Median:4696.69 stddev:465.48 (9.9%) stddev-sans-first:439.39 06:39:59 INFO - PROCESS | 17744 | Values: 2555.7 2609.6 2651.6 2663.0 2664.6 4490.4 4516.4 4638.4 4651.6 4666.1 4497.3 4547.6 4575.4 4585.1 4599.0 4150.6 4223.2 4225.6 4237.6 4272.7 4249.5 4292.3 4299.8 4335.7 4340.3 4252.7 4292.6 4312.2 4334.1 4354.1 4184.1 4209.6 4214.7 4250.7 4269.4 4226.7 4243.7 4259.3 4262.7 4329.6 4641.7 4658.2 4700.5 4706.3 4708.1 4803.4 4913.3 4963.3 4973.1 5021.3 5001.6 5046.0 5049.5 5057.9 5060.5 4676.9 4705.6 4710.1 4719.4 4721.5 4843.3 4966.9 4969.7 5155.1 5162.7 4794.9 4833.8 4836.8 4856.3 4858.8 4883.6 4901.0 4923.3 4931.8 4946.1 4718.1 4822.5 4858.1 4908.8 4927.6 4874.9 4883.6 4894.5 4907.9 4948.6 4184.8 4185.2 4191.0 4211.6 4246.7 3584.8 3627.3 3645.5 3696.6 3730.7 4327.8 4339.9 4347.1 4348.0 4393.7 4980.3 4983.2 4983.6 4988.1 5027.5 4223.8 4302.9 4357.7 4359.2 4368.6 4450.7 4480.4 4502.4 4510.0 4541.1 4632.1 4802.2 4808.0 4811.8 4841.0 4873.4 4941.2 4945.5 4950.0 4954.8 4727.0 4746.1 4755.3 4755.5 4779.9 4696.7 4863.8 4868.1 4880.0 4924.0 4976.5 4982.9 5001.8 5003.6 5041.6 4700.9 4719.6 4734.7 4738.6 4782.2 4495.6 4581.2 4653.0 4738.2 4769.2 4599.2 4634.1 4634.8 4637.2 4655.2 4755.1 4760.2 4766.2 4806.3 4810.4 4437.3 4446.6 4555.5 4622.2 4647.0 06:39:59 INFO - PROCESS | 17744 | 06:39:59 INFO - PROCESS | 17744 | [#1] ext.html Cycles:180 Average:48442.93 Median:3522.69 stddev:86995.31 (2469.6%) stddev-sans-first:87165.09 06:39:59 INFO - PROCESS | 17744 | Values: 568.2 570.4 578.1 588.8 589.5 27824.3 41374.2 42571.8 42673.8 45218.0 41031.0 41295.2 41386.6 41418.2 41429.8 7603.8 7885.0 7997.3 8006.0 8023.5 7630.4 7796.2 7799.7 10089.0 10400.1 10378.8 10429.3 10437.5 10442.5 10445.2 10298.3 10319.6 10334.2 10334.4 10347.4 7483.6 7707.3 7707.9 9757.2 10135.4 39534.6 39587.2 39878.0 39905.8 39993.0 2765.2 3049.9 3065.7 3091.8 3483.7 30003.2 30311.0 30438.5 30825.2 32033.7 3491.0 3496.4 3518.2 3527.2 3592.0 285215.0 286716.0 287714.0 289102.6 289251.0 53698.7 54522.7 54523.0 54588.0 54656.7 58264.4 58691.8 58759.0 58804.4 58931.7 13768.7 14332.7 14376.4 14384.3 14393.9 10034.1 10166.8 10171.3 10172.0 10210.6 18127.4 18244.9 18357.5 18609.9 18617.9 6706.7 6721.8 6896.1 6941.2 6949.7 801.7 873.1 892.3 893.6 954.4 27042.9 27669.6 28466.9 28493.1 28516.6 957.4 979.3 991.5 1009.3 1018.2 14669.8 15244.8 15287.6 15443.3 15548.1 395154.0 396682.0 398566.0 398998.0 399224.0 33466.5 33784.7 49282.5 49473.0 49477.8 1081.9 1117.5 1131.3 1193.7 1202.4 28363.4 29284.4 29287.1 29656.7 30140.0 41620.6 41882.6 41907.2 41951.2 42003.0 293998.0 295202.0 295949.0 296315.0 296878.0 21730.0 22133.0 23577.9 27446.6 27448.3 41604.0 41821.4 41905.8 42765.6 42896.0 36497.6 36947.3 36986.4 37462.4 37573.6 35940.0 36367.3 36618.3 36901.8 36937.6 35897.3 35992.6 36087.0 36422.0 36732.4 31742.4 32620.5 33109.5 33181.8 33208.7 25204.5 25300.0 25368.0 25482.1 25668.5 06:39:59 INFO - PROCESS | 17744 | 06:39:59 INFO - PROCESS | 17744 | [#2] jquery.html Cycles:180 Average:21789.37 Median:5326.29 stddev:52356.75 (983.0%) stddev-sans-first:52478.90 06:39:59 INFO - PROCESS | 17744 | Values: 361.2 373.3 374.9 382.7 389.0 16678.5 18277.4 18339.2 19004.6 19042.0 17516.3 17867.1 18244.5 18251.7 18348.0 5110.3 5272.4 5289.8 5319.2 5333.4 5214.6 5347.5 5676.8 5682.3 5785.9 5711.6 5732.7 5741.6 5745.6 5758.2 5525.5 5711.7 5718.1 5719.0 5719.8 4844.8 5496.6 5505.3 5512.3 5513.7 12479.1 13185.8 13210.5 13210.7 13510.5 1862.8 1867.3 1874.5 1889.1 1893.7 9019.3 9140.1 9263.1 9310.6 9621.0 1715.5 1716.7 1716.9 1724.7 1782.5 67798.7 72088.6 72416.6 73910.6 74809.0 10216.2 10229.3 10414.5 10454.8 11216.4 14339.9 14461.9 14464.9 14482.7 14484.5 6970.1 7196.6 7208.2 7233.3 7316.2 6822.4 6879.6 6885.4 6923.6 7012.4 4486.3 4488.2 4493.9 4541.9 4573.6 1828.2 1845.6 1867.8 1891.6 1898.9 830.4 837.4 861.6 872.2 952.2 17391.7 17571.3 17910.8 18174.5 18520.2 841.0 847.2 875.8 879.0 915.3 9342.5 9705.9 10093.3 10197.5 10278.9 132488.0 300170.0 304896.0 307018.0 433389.0 50859.0 71653.6 78366.6 85896.6 93184.0 890.9 905.0 909.9 923.4 946.4 16533.9 16575.0 16762.3 16887.7 17430.1 26730.7 28055.9 40293.0 40492.4 40731.6 56386.7 58046.0 58095.7 58290.7 59366.0 7239.6 7252.7 7286.5 7292.9 7313.3 6647.8 9549.0 11251.5 11487.5 11611.1 9695.4 9725.6 9749.4 9901.6 10087.3 9839.4 9903.6 9952.0 10091.4 10178.0 9531.4 10076.4 10097.5 10142.5 10507.8 7457.3 7467.3 7500.9 7637.6 7728.1 8260.7 9530.1 9569.9 9637.0 10227.1 06:39:59 INFO - PROCESS | 17744 | 06:39:59 INFO - PROCESS | 17744 | [#3] mootools.html Cycles:180 Average:4860.90 Median:5067.68 stddev:2672.92 (52.7%) stddev-sans-first:2666.54 06:39:59 INFO - PROCESS | 17744 | Values: 1236.3 1349.1 1394.8 1451.8 1663.2 6307.1 7112.5 7377.2 7388.1 7555.8 7268.5 7405.4 7413.3 7417.9 7421.6 2170.2 2192.6 2194.8 2195.7 2198.0 2138.6 2185.6 2189.2 2190.1 2193.1 2161.2 2175.9 2188.8 2194.1 2195.0 2123.5 2123.5 2133.4 2134.8 2137.6 2054.1 2089.9 2090.4 2091.5 2094.5 7283.1 7302.9 7400.2 7405.4 7489.9 4449.9 4548.0 4552.5 4554.1 4563.0 2693.6 2757.3 2781.3 2799.1 2808.3 834.6 839.6 846.5 851.1 856.2 9997.8 10091.3 10104.4 10117.2 10123.8 3849.0 3963.6 4077.7 4122.2 4126.9 7874.1 7932.6 7938.8 7942.2 7944.7 4507.9 4517.1 4519.3 4522.1 4524.6 3695.1 3912.2 4054.7 4235.8 4237.0 3341.8 3380.8 3426.6 3450.9 3539.7 1014.6 1018.7 1031.8 1033.1 1033.2 650.3 711.8 723.7 739.2 804.0 5073.5 5086.4 5164.1 5208.7 5255.6 432.5 447.7 450.0 451.9 606.8 3537.4 3711.9 3731.2 3750.4 3758.8 8576.2 8881.1 8993.0 9002.6 9019.1 8916.2 8918.3 8924.8 8926.0 8928.4 6861.3 6945.3 6950.2 6952.4 6954.5 4988.9 5035.8 5061.8 5109.6 5147.5 8349.9 8376.0 8413.8 8437.8 8452.3 7837.7 8117.4 8145.6 8344.3 8495.0 5683.4 6250.2 6456.4 6488.8 6585.3 7273.5 7281.9 7339.5 7349.9 7358.1 6268.3 6558.7 6564.1 6654.5 6666.8 6392.2 6463.3 6475.0 6604.9 6647.1 5603.0 5663.3 5710.6 5715.3 5733.6 4204.8 4369.4 4383.1 4393.7 4456.9 4401.5 4442.8 4557.6 4591.2 4595.9 06:39:59 INFO - PROCESS | 17744 | 06:39:59 INFO - PROCESS | 17744 | [#4] prototype.html Cycles:180 Average:4156.87 Median:4748.14 stddev:1187.13 (25.0%) stddev-sans-first:1154.65 06:39:59 INFO - PROCESS | 17744 | Values: 301.4 306.4 308.9 308.9 310.1 4829.3 4839.8 4842.4 4869.5 4887.9 4794.2 4796.3 4816.3 4821.7 4823.3 3616.9 3656.2 3726.6 3740.6 3797.8 3774.8 3786.4 3797.2 3828.3 3832.2 3603.2 3742.1 3757.2 3767.9 3778.3 3538.3 3699.0 3714.7 3715.0 3756.7 3307.1 3312.7 3313.6 3327.2 3366.6 4616.9 4633.7 4643.0 4648.6 4663.4 4219.9 4227.8 4249.9 4257.1 4316.3 4925.9 4934.4 4958.4 4963.6 4969.8 3632.7 3637.7 3643.5 3661.7 3664.4 5027.0 5165.0 5211.9 5236.2 5245.2 3675.5 3701.6 3702.8 3705.4 3707.5 3758.9 3760.1 3761.6 3763.8 3766.4 4254.9 4272.3 4280.0 4281.7 4286.8 4777.0 4975.8 4984.0 4984.2 5005.2 1307.0 1322.9 1330.2 1330.8 1336.6 839.9 865.7 879.3 881.6 882.2 4283.5 4377.7 4431.2 4472.8 4482.5 5138.7 5156.8 5157.2 5184.4 5191.1 4476.4 4484.1 4485.1 4508.8 4512.2 2638.8 2737.4 2743.4 2746.6 2749.7 4910.2 4911.0 4943.6 4970.4 4999.9 5019.4 5272.6 5278.7 5311.9 5323.0 4901.6 4917.3 4923.8 4934.0 4938.5 5116.1 5122.8 5125.8 5202.5 5208.2 4940.4 5044.5 5087.0 5093.9 5112.9 4874.6 4888.3 4901.7 4914.5 4920.0 3699.1 3718.0 3759.7 3770.5 3779.4 4986.6 5200.2 5209.3 5209.6 5238.3 4774.8 4809.5 4831.0 4837.6 4854.3 4854.6 4856.1 4864.3 4926.2 4936.8 4501.5 4728.8 4736.0 4743.9 4752.8 4674.3 4764.9 4816.7 4858.0 4893.0 4698.0 4752.4 4753.4 4755.2 4776.3 06:39:59 INFO - PROCESS | 17744 | 06:39:59 INFO - PROCESS | 17744 | [#5] yui.html Cycles:180 Average:16495.26 Median:24746.88 stddev:22593.80 (91.3%) stddev-sans-first:22625.89 06:39:59 INFO - PROCESS | 17744 | Values: 658.4 682.1 693.1 746.7 748.7 11976.2 12657.3 12700.7 12741.6 12778.2 12832.4 12955.9 12998.4 13000.0 13816.2 782.2 803.3 804.2 804.3 804.4 622.4 760.9 763.9 767.1 789.0 822.4 823.7 824.4 824.7 824.8 820.2 820.4 820.4 821.0 822.5 479.6 588.3 601.3 603.4 604.5 13338.1 13359.2 13367.7 13402.3 13415.2 12337.6 12610.7 12795.3 12812.6 13192.9 11917.7 11924.3 11987.6 12262.8 12611.2 3592.3 3601.0 3627.3 3711.6 3737.2 61851.7 62204.7 62252.7 62919.7 63062.7 10039.4 10187.7 10294.9 10336.5 10648.9 18427.8 18493.5 18512.4 18531.3 18544.4 6821.0 6931.7 7000.9 7066.0 7079.7 3478.4 3667.0 3783.4 3809.1 3826.3 2548.2 2642.8 2644.6 2649.5 2650.3 462.0 475.5 476.6 478.7 483.0 460.5 508.2 525.0 563.2 567.4 7891.4 8077.9 8389.8 8513.8 8585.6 571.6 583.7 586.5 586.9 593.7 3914.2 3970.3 3989.4 4062.0 4073.8 88671.1 88714.6 90639.0 91751.0 94182.0 49010.8 49054.0 49625.8 49703.0 50758.7 58976.0 71351.3 71560.6 71938.3 72858.6 7673.8 7687.7 7747.4 7771.4 7866.9 44443.6 45011.8 45445.1 45568.1 46194.1 61592.0 65716.3 65720.0 66185.0 66509.0 9049.2 9068.1 9138.4 9168.1 9216.2 9989.6 10057.8 10255.2 10314.7 10491.2 8394.6 9405.7 9551.5 9711.0 9838.4 11582.6 12212.7 12412.0 12458.9 12609.9 12245.1 12409.3 12716.4 12909.0 12958.4 10378.0 10745.7 10751.2 10803.6 10849.8 10615.5 10901.5 11091.0 11109.3 11117.6 06:39:59 INFO - PROCESS | 17744 | -------- Summary: end -------- 06:39:59 INFO - PROCESS | 17744 | 06:40:00 INFO - TEST-INFO | 17744: exit 0 06:40:00 INFO - TEST-OK | dromaeo_css | took 1119240ms 06:40:00 INFO - TEST-START | kraken 06:40:00 INFO - operating with platform_type : linux_ 06:40:00 INFO - Initialising browser for kraken test... 06:40:00 INFO - TEST-INFO | started process 17935 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsg7uza/profile http://localhost:56326/getInfo.html) 06:40:04 INFO - PROCESS | 17935 | __metrics Screen width/height:1600/1200 06:40:04 INFO - PROCESS | 17935 | colorDepth:24 06:40:04 INFO - PROCESS | 17935 | Browser inner width/height: 1024/697 06:40:04 INFO - PROCESS | 17935 | __metrics 06:40:05 INFO - PROCESS | 17935 | 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" 06:40:05 INFO - PROCESS | 17935 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:40:06 INFO - PROCESS | 17935 | console.error: PushService: 06:40:06 INFO - PROCESS | 17935 | stateChangeProcessEnqueue: Error transitioning state 06:40:06 INFO - PROCESS | 17935 | UnknownError 06:40:06 INFO - TEST-INFO | 17935: exit 0 06:40:06 INFO - Browser initialized. 06:40:06 INFO - Running cycle 1/1 for kraken test... 06:40:06 INFO - TEST-INFO | started process 18016 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsg7uza/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:40:18 INFO - PROCESS | 18016 | RSS: Main: 158732288 06:40:18 INFO - PROCESS | 18016 | 06:40:21 INFO - PROCESS | 18016 | Cycle 1(1): loaded http://localhost:56326/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56326/tests/kraken/driver.html?testName=audio-beat-detection) 06:40:22 INFO - PROCESS | 18016 | RSS: Main: 217473024 06:40:22 INFO - PROCESS | 18016 | 06:40:25 INFO - PROCESS | 18016 | Cycle 1(1): loaded http://localhost:56326/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56326/tests/kraken/driver.html?testName=audio-dft) 06:40:25 INFO - PROCESS | 18016 | RSS: Main: 206180352 06:40:25 INFO - PROCESS | 18016 | 06:40:29 INFO - PROCESS | 18016 | Cycle 1(1): loaded http://localhost:56326/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56326/tests/kraken/driver.html?testName=audio-fft) 06:40:29 INFO - PROCESS | 18016 | RSS: Main: 272023552 06:40:29 INFO - PROCESS | 18016 | 06:40:31 INFO - PROCESS | 18016 | Cycle 1(1): loaded http://localhost:56326/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56326/tests/kraken/driver.html?testName=audio-oscillator) 06:40:32 INFO - PROCESS | 18016 | RSS: Main: 182427648 06:40:32 INFO - PROCESS | 18016 | 06:40:34 INFO - PROCESS | 18016 | Cycle 1(1): loaded http://localhost:56326/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56326/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:40:35 INFO - PROCESS | 18016 | RSS: Main: 186527744 06:40:35 INFO - PROCESS | 18016 | 06:40:38 INFO - PROCESS | 18016 | Cycle 1(1): loaded http://localhost:56326/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56326/tests/kraken/driver.html?testName=imaging-darkroom) 06:40:39 INFO - PROCESS | 18016 | RSS: Main: 222064640 06:40:39 INFO - PROCESS | 18016 | 06:40:42 INFO - PROCESS | 18016 | Cycle 1(1): loaded http://localhost:56326/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56326/tests/kraken/driver.html?testName=imaging-desaturate) 06:40:43 INFO - PROCESS | 18016 | RSS: Main: 222224384 06:40:43 INFO - PROCESS | 18016 | 06:40:46 INFO - PROCESS | 18016 | Cycle 1(1): loaded http://localhost:56326/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56326/tests/kraken/driver.html?testName=json-parse-financial) 06:40:46 INFO - PROCESS | 18016 | RSS: Main: 222527488 06:40:46 INFO - PROCESS | 18016 | 06:40:48 INFO - PROCESS | 18016 | Cycle 1(1): loaded http://localhost:56326/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56326/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:40:49 INFO - PROCESS | 18016 | RSS: Main: 210329600 06:40:49 INFO - PROCESS | 18016 | 06:40:50 INFO - PROCESS | 18016 | Cycle 1(1): loaded http://localhost:56326/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56326/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:40:51 INFO - PROCESS | 18016 | RSS: Main: 216248320 06:40:51 INFO - PROCESS | 18016 | 06:40:53 INFO - PROCESS | 18016 | Cycle 1(1): loaded http://localhost:56326/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56326/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:40:54 INFO - PROCESS | 18016 | RSS: Main: 212586496 06:40:54 INFO - PROCESS | 18016 | 06:40:57 INFO - PROCESS | 18016 | Cycle 1(1): loaded http://localhost:56326/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56326/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:40:57 INFO - PROCESS | 18016 | RSS: Main: 223244288 06:40:57 INFO - PROCESS | 18016 | 06:41:00 INFO - PROCESS | 18016 | Cycle 1(1): loaded http://localhost:56326/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56326/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:41:01 INFO - PROCESS | 18016 | RSS: Main: 225173504 06:41:01 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | Cycle 1(1): loaded http://localhost:56326/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56326/tests/kraken/driver.html?testName=ai-astar) 06:41:03 INFO - PROCESS | 18016 | RSS: Main: 267964416 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | __start_tp_report 06:41:03 INFO - PROCESS | 18016 | _x_x_mozilla_page_load 06:41:03 INFO - PROCESS | 18016 | _x_x_mozilla_page_load_details 06:41:03 INFO - PROCESS | 18016 | |i|pagename|runs| 06:41:03 INFO - PROCESS | 18016 | |0;ai-astar;105;117;115;114;113;117;117;113;115;116 06:41:03 INFO - PROCESS | 18016 | |1;audio-beat-detection;144;122;124;170;129;124;146;127;175;123 06:41:03 INFO - PROCESS | 18016 | |2;audio-dft;160;162;162;160;160;161;160;160;161;161 06:41:03 INFO - PROCESS | 18016 | |3;audio-fft;77;76;77;111;79;78;75;76;107;76 06:41:03 INFO - PROCESS | 18016 | |4;audio-oscillator;85;85;121;85;85;85;117;84;85;124 06:41:03 INFO - PROCESS | 18016 | |5;imaging-gaussian-blur;117;116;117;117;117;117;117;117;117;117 06:41:03 INFO - PROCESS | 18016 | |6;imaging-darkroom;146;146;146;146;146;147;146;146;147;146 06:41:03 INFO - PROCESS | 18016 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;94;96 06:41:03 INFO - PROCESS | 18016 | |8;json-parse-financial;65;64;66;65;65;65;64;62;64;64 06:41:03 INFO - PROCESS | 18016 | |9;json-stringify-tinderbox;64;63;63;64;64;64;64;64;63;64 06:41:03 INFO - PROCESS | 18016 | |10;stanford-crypto-aes;81;83;79;78;79;79;78;79;79;78 06:41:03 INFO - PROCESS | 18016 | |11;stanford-crypto-ccm;128;127;136;128;129;130;131;128;206;133 06:41:03 INFO - PROCESS | 18016 | |12;stanford-crypto-pbkdf2;163;164;167;170;166;166;169;164;167;164 06:41:03 INFO - PROCESS | 18016 | |13;stanford-crypto-sha256-iterative;66;69;66;67;65;65;67;65;68;66 06:41:03 INFO - PROCESS | 18016 | __end_tp_report 06:41:03 INFO - PROCESS | 18016 | __start_cc_report 06:41:03 INFO - PROCESS | 18016 | _x_x_mozilla_cycle_collect,1922 06:41:03 INFO - PROCESS | 18016 | __end_cc_report 06:41:03 INFO - PROCESS | 18016 | __startTimestamp1468330863272__endTimestamp 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | ------- Summary: start ------- 06:41:03 INFO - PROCESS | 18016 | Number of tests: 14 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | [#0] ai-astar Cycles:10 Average:114.20 Median:115.50 stddev:3.58 (3.1%) stddev-sans-first:1.64 06:41:03 INFO - PROCESS | 18016 | Values: 105.0 117.0 115.0 114.0 113.0 117.0 117.0 113.0 115.0 116.0 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:136.50 stddev:19.91 (14.6%) stddev-sans-first:21.01 06:41:03 INFO - PROCESS | 18016 | Values: 144.0 122.0 124.0 170.0 129.0 124.0 146.0 127.0 175.0 123.0 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 06:41:03 INFO - PROCESS | 18016 | Values: 160.0 162.0 162.0 160.0 160.0 161.0 160.0 160.0 161.0 161.0 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | [#3] audio-fft Cycles:10 Average:83.20 Median:76.50 stddev:13.68 (17.9%) stddev-sans-first:14.32 06:41:03 INFO - PROCESS | 18016 | Values: 77.0 76.0 77.0 111.0 79.0 78.0 75.0 76.0 107.0 76.0 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:85.00 stddev:17.38 (20.4%) stddev-sans-first:18.01 06:41:03 INFO - PROCESS | 18016 | Values: 85.0 85.0 121.0 85.0 85.0 85.0 117.0 84.0 85.0 124.0 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 06:41:03 INFO - PROCESS | 18016 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 06:41:03 INFO - PROCESS | 18016 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 147.0 146.0 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 06:41:03 INFO - PROCESS | 18016 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 96.0 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:65.00 stddev:1.07 (1.7%) stddev-sans-first:1.12 06:41:03 INFO - PROCESS | 18016 | Values: 65.0 64.0 66.0 65.0 65.0 65.0 64.0 62.0 64.0 64.0 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | [#9] json-stringify-tinderbox Cycles:10 Average:63.70 Median:64.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 06:41:03 INFO - PROCESS | 18016 | Values: 64.0 63.0 63.0 64.0 64.0 64.0 64.0 64.0 63.0 64.0 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | [#10] stanford-crypto-aes Cycles:10 Average:79.30 Median:79.00 stddev:1.57 (2.0%) stddev-sans-first:1.54 06:41:03 INFO - PROCESS | 18016 | Values: 81.0 83.0 79.0 78.0 79.0 79.0 78.0 79.0 79.0 78.0 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | [#11] stanford-crypto-ccm Cycles:10 Average:137.60 Median:130.50 stddev:24.19 (18.5%) stddev-sans-first:25.41 06:41:03 INFO - PROCESS | 18016 | Values: 128.0 127.0 136.0 128.0 129.0 130.0 131.0 128.0 206.0 133.0 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.00 Median:166.50 stddev:2.31 (1.4%) stddev-sans-first:2.18 06:41:03 INFO - PROCESS | 18016 | Values: 163.0 164.0 167.0 170.0 166.0 166.0 169.0 164.0 167.0 164.0 06:41:03 INFO - PROCESS | 18016 | 06:41:03 INFO - PROCESS | 18016 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.50 stddev:1.35 (2.0%) stddev-sans-first:1.42 06:41:03 INFO - PROCESS | 18016 | Values: 66.0 69.0 66.0 67.0 65.0 65.0 67.0 65.0 68.0 66.0 06:41:03 INFO - PROCESS | 18016 | -------- Summary: end -------- 06:41:03 INFO - PROCESS | 18016 | 06:41:04 INFO - TEST-INFO | 18016: exit 0 06:41:04 INFO - TEST-OK | kraken | took 64015ms 06:41:04 INFO - SUITE-END | took 1183s 06:41:04 INFO - Completed test suite (00:19:43) 06:41:04 INFO - javascript benchmark 06:41:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2555.667763190785, 2609.5694210455276, 2651.6420283261755, 2662.9600555991665, 2664.586989016702, 4490.416245888038, 4516.390287311212, 4638.373819534362, 4651.581357677808, 4666.113438446697, 4497.3029180477, 4547.567981041804, 4575.405197324343, 4585.082983403325, 4598.97700511497, 4150.64719498843, 4223.176480586287, 4225.598568136037, 4237.6186143247105, 4272.743635381871, 4249.51130619978, 4292.334688123346, 4299.785010749466, 4335.739855608658, 4340.327249276367, 4252.681048921343, 4292.61366477017, 4312.22379971605, 4334.133173365324, 4354.08564201518, 4184.14225083857, 4209.557996410364, 4214.683898707579, 4250.659947204216, 4269.444972153631, 4226.725262857904, 4243.702940794145, 4259.2759230930815, 4262.722923009995, 4329.610335069828, 4641.675082744209, 4658.231391820345, 4700.482946875841, 4706.270528068155, 4708.058388322349, 4803.351547541107, 4913.31213630092, 4963.3299504566785, 4973.055119527315, 5021.322121513622, 5001.574866136383, 5045.974770126126, 5049.5455409013, 5057.949420505822, 5060.493950604947, 4676.859694209179, 4705.623550116016, 4710.105080034782, 4719.38647975761, 4721.504242054603, 4843.2735089736825, 4966.8758281043265, 4969.676970996874, 5155.123628983082, 5162.6902385856965, 4794.880127996828, 4833.806647734122, 4836.830710925101, 4856.2958371036, 4858.82994095205, 4883.633727470383, 4901.019796040806, 4923.335349727812, 4931.778069986859, 4946.109700253989, 4718.05638872227, 4822.517748225149, 4858.149823780825, 4908.828191013297, 4927.630427717935, 4874.902501949941, 4883.609311255091, 4894.510548945077, 4907.877303067452, 4948.604111671058, 4184.811683474251, 4185.22573323936, 4190.958090419118, 4211.557786432441, 4246.681498887596, 3584.8386822593047, 3627.2926779278314, 3645.489631451599, 3696.5748938871834, 3730.6828919542145, 4327.82688692455, 4339.913201736011, 4347.130573885235, 4347.956520434818, 4393.6704747144095, 4980.252962055648, 4983.202687570044, 4983.551480366785, 4988.052270068748, 5027.547520723152, 4223.831046758157, 4302.913941721148, 4357.673174511924, 4359.17175736609, 4368.628666563378, 4450.710703804242, 4480.395146655225, 4502.414686090853, 4509.954900450954, 4541.069080838421, 4632.119897219518, 4802.183628783185, 4807.95192048075, 4811.759412029314, 4841.007593443335, 4873.390826146731, 4941.184704523714, 4945.480724524017, 4950.034743225036, 4954.826581069645, 4727.030958653537, 4746.098241334135, 4755.334253204485, 4755.548222918817, 4779.904401911873, 4696.694714843524, 4863.805447782049, 4868.123737727243, 4880.023995201045, 4923.950760492349, 4976.527229913152, 4982.900341993212, 5001.7749201285305, 5003.624728145331, 5041.596672266283, 4700.858974230779, 4719.551642593948, 4734.692245004064, 4738.644601654959, 4782.163121453801, 4495.640348772025, 4581.198290299251, 4652.976735116303, 4738.241881298975, 4769.165396055746, 4599.218132917479, 4634.119517291704, 4634.76826158684, 4637.188492013952, 4655.18534256497, 4755.072760811747, 4760.190767569452, 4766.165920963748, 4806.327114203944, 4810.4467986182035, 4437.33439984005, 4446.599806017474, 4555.544445555418, 4622.237330840374, 4647.047355292225], "value": 4537.047761274041, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.2470726287668, 570.375438894411, 578.057765841678, 588.8204097750187, 589.4900910712233, 27824.304392390175, 41374.17251654969, 42571.78714106433, 42673.7866310668, 45218.0, 41031.0, 41295.17409651805, 41386.58613413865, 41418.17163656725, 41429.7928510357, 7603.80990475237, 7884.974953256069, 7997.320227780646, 8005.959970200141, 8023.518588884685, 7630.42771792115, 7796.220377962215, 7799.727009554638, 10089.0, 10400.063994240516, 10378.844317335246, 10429.3220940639, 10437.478126093665, 10442.530086146104, 10445.216608754336, 10298.279120461571, 10319.638812641522, 10334.22493312997, 10334.431606261653, 10347.430891300975, 7483.625818709097, 7707.306342429209, 7707.884381734279, 9757.170640495566, 10135.44255065971, 39534.60465395338, 39587.208255834725, 39878.0, 39905.800470997456, 39993.0, 2765.2119146043283, 3049.8932537361306, 3065.7240848323536, 3091.7526597872115, 3483.651634836521, 30003.249918752204, 30311.0, 30438.54342184869, 30825.229369265948, 32033.679663203537, 3490.982545087258, 3496.3531746626954, 3518.1732292911147, 3527.206378564828, 3591.964080359189, 285215.0, 286716.0, 287714.0, 289102.55448722834, 289251.0, 53698.731506343, 54522.727386362814, 54523.0, 54588.0, 54656.72671636617, 58264.4173558259, 58691.77195254302, 58759.0, 58804.41195588075, 58931.70534147302, 13768.724625507633, 14332.713345733027, 14376.424943002163, 14384.28078596066, 14393.92803035999, 10034.147097496794, 10166.847497287547, 10171.338862973884, 10172.033656802592, 10210.591576336863, 18127.371531127912, 18244.90877545604, 18357.541061473305, 18609.90695046516, 18617.90691046536, 6706.698198581075, 6721.764738234138, 6896.137982752156, 6941.245247005817, 6949.687264073129, 801.7234054251275, 873.0861072521553, 892.2505095719627, 893.584483215308, 954.398728800851, 27042.864785675945, 27669.584956225674, 28466.857665711537, 28493.145205643865, 28516.572251416244, 957.4255446731905, 979.329159525713, 991.5141580625586, 1009.3086235928413, 1018.2007124407305, 14669.77995330071, 15244.847551524565, 15287.617809554627, 15443.30505127272, 15548.067115973077, 395154.0, 396682.0, 398566.0, 398998.0, 399224.0, 33466.49800252949, 33784.66215337815, 49282.50717492779, 49473.0, 49477.75261123671, 1081.9242653014214, 1117.5027112934665, 1131.2646779593001, 1193.7015746063485, 1202.3567391445536, 28363.432731344845, 29284.414311714074, 29287.12138635844, 29656.703432966257, 30140.0, 41620.58379416167, 41882.58117418787, 41907.161856763305, 41951.160976780906, 42003.0, 293998.0, 295202.0, 295949.0, 296315.0, 296878.0, 21730.0, 22133.0, 23577.882110589337, 27446.5883011751, 27448.313792154557, 41604.0, 41821.372679410444, 41905.79047104745, 42765.57234427616, 42896.0, 36497.635023649425, 36947.261054779294, 36986.44520332143, 37462.43806342962, 37573.62426375701, 35940.0, 36367.27265454623, 36618.26763464769, 36901.81549092345, 36937.630623694495, 35897.28205435929, 35992.63958257898, 36087.0, 36422.0, 36732.44901326429, 31742.36515269728, 32620.496301598778, 33109.503357450136, 33181.83409082939, 33208.667913320554, 25204.49591008133, 25300.0, 25368.0, 25482.050282206288, 25668.486630267664], "value": 17994.817742367522, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.246800421122, 373.3298728781838, 374.89315545069644, 382.6881091910091, 389.0216106492173, 16678.499645010637, 18277.36029238977, 18339.17473713683, 19004.61990760184, 19042.047897605116, 17516.299348026063, 17867.141257398787, 18244.452666419995, 18251.72622410665, 18348.0, 5110.335656364677, 5272.367315922075, 5289.841304760844, 5319.175527793198, 5333.413324534298, 5214.608904332172, 5347.465253474641, 5676.829695109132, 5682.26130603021, 5785.884282314351, 5711.628744131618, 5732.7133643317875, 5741.598088133832, 5745.626534275259, 5758.193852860602, 5525.475079867406, 5711.657300561979, 5718.1136923776885, 5718.971405142989, 5719.799807006755, 4844.757762111915, 5496.560275177976, 5505.311836020497, 5512.283403157604, 5513.696746678931, 12479.126461147724, 13185.802212966813, 13210.537631182959, 13210.66973325665, 13510.527131550447, 1862.7857796353321, 1867.2997625890291, 1874.5407375193029, 1889.0649128681391, 1893.6970084786506, 9019.331423686574, 9140.085991400872, 9263.120003599648, 9310.627574896997, 9621.037896210393, 1715.476779582227, 1716.7253239481622, 1716.8712346574057, 1724.6981778188767, 1782.4919897829186, 67798.66100669514, 72088.6395568024, 72416.6379168106, 73910.63044684796, 74809.0, 10216.182705383551, 10229.335093218917, 10414.531346089445, 10454.790904181873, 11216.439178041228, 14339.928300358431, 14461.855381446261, 14464.927675361767, 14482.710345793024, 14484.493042743665, 6970.059042029363, 7196.568205907663, 7208.171060328129, 7233.31283528064, 7316.158641756266, 6822.3859852613505, 6879.58722476653, 6885.449164066863, 6923.6192009439965, 7012.439004879598, 4486.2597671383855, 4488.192125417456, 4493.910121797546, 4541.863744087683, 4573.63410927125, 1828.232142500153, 1845.5570663040774, 1867.8412334951681, 1891.5649400637656, 1898.924043038263, 830.3647709502114, 837.3719710217337, 861.629499315288, 872.1975782280317, 952.2477242977969, 17391.65216695659, 17571.297148114187, 17910.820891791176, 18174.545636358933, 18520.16659250337, 841.022779361055, 847.2163248994656, 875.7941883657336, 879.0286733159767, 915.3135148638521, 9342.53287335644, 9705.902940970642, 10093.343932644355, 10197.541110650038, 10278.948605256926, 132488.0, 300170.0, 304896.0, 307018.0, 433389.0, 50859.0, 71653.64173179206, 78366.60816695994, 85896.57051714702, 93184.0, 890.8530092534788, 904.9954550448996, 909.9272058235325, 923.422860712055, 946.4321407155652, 16533.91733041327, 16575.0, 16762.32950682008, 16887.66224675524, 17430.128493575525, 26730.697454527202, 28055.859720701264, 40293.0, 40492.39261411022, 40731.59268407278, 56386.718066409405, 58046.0, 58095.709521452125, 58290.708546457, 59366.0, 7239.565626062453, 7252.709891604488, 7286.453515986428, 7292.927070729224, 7313.305236002571, 6647.833804154935, 9548.952255238679, 11251.493682784458, 11487.54049837997, 11611.071114311004, 9695.418274903528, 9725.610975560898, 9749.36629119105, 9901.55443005052, 10087.344322619007, 9839.360441571274, 9903.55434005457, 9951.950240249042, 10091.44497052669, 10178.0, 9531.38046027006, 10076.395416274754, 10097.5456104474, 10142.483686216587, 10507.842382364117, 7457.291557302047, 7467.2532746727065, 7500.924990750241, 7637.618119093912, 7728.111267204119, 8260.669573217245, 9530.094641009093, 9569.90430095718, 9637.0, 10227.130693891102], "value": 8127.671441458164, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1236.264422668512, 1349.102846607006, 1394.7907813827924, 1451.789490523874, 1663.2265996311721, 6307.148534947781, 7112.466565007626, 7377.225391333913, 7388.076490438689, 7555.811104722385, 7268.527545709539, 7405.407567394609, 7413.258674132598, 7417.851642967137, 7421.5547067176, 2170.175333373316, 2192.572448372568, 2194.8244140468723, 2195.659672750718, 2197.9780202198012, 2138.5936672032262, 2185.595664802017, 2189.157174487818, 2190.080166330137, 2193.056985496236, 2161.200355868323, 2175.923842665507, 2188.792064753854, 2194.122351059581, 2194.9780502195013, 2123.50097727034, 2123.5115923337567, 2133.4026472587702, 2134.8078672919432, 2137.551114266006, 2054.075665950328, 2089.9268525601533, 2090.383336915598, 2091.466675997626, 2094.518260800009, 7283.053203083565, 7302.926970730277, 7400.222976587488, 7405.407567394649, 7489.887651685229, 4449.866504004885, 4548.044910568785, 4552.4764652065105, 4554.066416384666, 4563.0, 2693.62289279501, 2757.2968892932377, 2781.3185769486604, 2799.14626039058, 2808.2979255186206, 834.5701963488748, 839.6431516605479, 846.4963346808639, 851.1148405658108, 856.2379482260793, 9997.800043999152, 10091.29360944734, 10104.444255565868, 10117.241206909437, 10123.848142277871, 3849.0, 3963.6432721054666, 4077.734947228421, 4122.154958233555, 4126.8761937141935, 7874.05511338643, 7932.603369831485, 7938.801529961682, 7942.205779422012, 7944.6822127114265, 4507.909841803145, 4517.119161763425, 4519.276915693474, 4522.118186953512, 4524.5701658342405, 3695.0762309422644, 3912.158885839558, 4054.655354294859, 4235.7670328131635, 4236.978815105905, 3341.766076374679, 3380.763346565766, 3426.623071462137, 3450.9482357764655, 3539.734519911017, 1014.6144465103214, 1018.6740243122277, 1031.8245898197324, 1033.1424917318607, 1033.178622994706, 650.2554575011585, 711.7900219435279, 723.7286017743346, 739.1942782367246, 803.999021218575, 5073.543381095646, 5086.389633243961, 5164.147915593885, 5208.687478751287, 5255.5532779713385, 432.4594257178527, 447.7291238800544, 449.95950364467353, 451.90058187198764, 606.7924829589086, 3537.4163263061305, 3711.9072023199637, 3731.2350968051483, 3750.437434384864, 3758.830852611639, 8576.228139467477, 8881.067487913806, 8993.0, 9002.594883230271, 9019.143181397756, 8916.242119419792, 8918.331125165638, 8924.821503569892, 8925.955370223108, 8928.419652722552, 6861.313868613199, 6945.2707465715375, 6950.20072691647, 6952.409045231212, 6954.478414118961, 4988.850334489971, 5035.773390197523, 5061.822836200715, 5109.565686916654, 5147.510986456281, 8349.874751878848, 8376.036755773164, 8413.831723365622, 8437.789055273668, 8452.281556067557, 7837.686492540234, 8117.350611950912, 8145.592720364077, 8344.290735287568, 8494.957525212334, 5683.374828768915, 6250.249970003629, 6456.354364563506, 6488.837779055561, 6585.341465853377, 7273.490855640266, 7281.854362912818, 7339.522931009467, 7349.853002940018, 7358.080239970004, 6268.310485846644, 6558.704858281484, 6564.113844630913, 6654.5341826073645, 6666.799995999933, 6392.20097487814, 6463.25672547663, 6475.0, 6604.933950660624, 6647.135872336566, 5602.971985140048, 5663.32040155184, 5710.5717071219, 5715.31416230055, 5733.569982251432, 4204.768737719332, 4369.410129632459, 4383.123375324884, 4393.670474714345, 4456.866294011184, 4401.537838526909, 4442.822287108479, 4557.635389168925, 4591.196540605448, 4595.9310610341545], "value": 3879.38237355946, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.4408272654226, 306.40557318801524, 308.8841684368362, 308.94284557356883, 310.07906517642596, 4829.251466022772, 4839.806407743686, 4842.370491836061, 4869.513048695128, 4887.853364399065, 4794.161021821176, 4796.328514008042, 4816.3016362627395, 4821.662483626147, 4823.252395878644, 3616.9095772605633, 3656.159083410817, 3726.6087060858627, 3740.56983446903, 3797.841658294219, 3774.792386418746, 3786.413105968579, 3797.2025874566375, 3828.25349056934, 3832.176082142339, 3603.2433189030203, 3742.120601658608, 3757.229767897574, 3767.943480847776, 3778.263238668461, 3538.327717733622, 3698.981505092471, 3714.7213958953184, 3715.0, 3756.69946404287, 3307.1236122427335, 3312.734981201498, 3313.6355000949875, 3327.15157634804, 3366.5791776027995, 4616.884577885546, 4633.652476064309, 4643.0, 4648.628109751212, 4663.440387153563, 4219.852305169335, 4227.788610569459, 4249.938779691847, 4257.1485702859245, 4316.330968699856, 4925.876853078666, 4934.432540257881, 4958.446400103913, 4963.60291176705, 4969.77636006377, 3632.7093832493338, 3637.672609465161, 3643.4899114123778, 3661.707063434919, 3664.4320130379833, 5026.974865125688, 5164.999999999963, 5211.941283193155, 5236.240745091978, 5245.167609490871, 3675.4854320395166, 3701.555813302421, 3702.8148592570265, 3705.3515634763808, 3707.4994875691978, 3758.8872333830027, 3760.0599850037493, 3761.605031471711, 3763.849446022183, 3766.39737641976, 4254.893627659333, 4272.33778764292, 4279.957200428018, 4281.721688090264, 4286.828526858953, 4776.952230477651, 4975.776090075955, 4983.950160498349, 4984.152694042022, 5005.224190250517, 1306.9869301307056, 1322.8544860065385, 1330.2018788726687, 1330.8089260112145, 1336.6123824091098, 839.9160083991552, 865.7143142762873, 879.2965627497977, 881.6422709028153, 882.1531329923217, 4283.464566929134, 4377.671674624416, 4431.202383570988, 4472.776361181928, 4482.529334419839, 5138.743062846843, 5156.793728250828, 5157.200633901752, 5184.351956005499, 5191.143461328838, 4476.395686582269, 4484.148011877733, 4485.125400546927, 4508.752018639007, 4512.165249428866, 2638.828476149044, 2737.397772490049, 2743.4101668141448, 2746.6292050572915, 2749.7112803155383, 4910.238912968424, 4911.0423467424225, 4943.555080042814, 4970.378702662168, 4999.875028118717, 5019.3976722793495, 5272.55183309423, 5278.703763862732, 5311.884504254072, 5322.973385133049, 4901.632377571767, 4917.254849070592, 4923.8276660316715, 4933.975330123399, 4938.506149385104, 5116.104681680766, 5122.820701275437, 5125.79496820138, 5202.453742356998, 5208.166693329201, 4940.357753492023, 5044.486654003711, 5087.008033433445, 5093.923591146062, 5112.923306150336, 4874.610031197567, 4888.291197776373, 4901.676547332129, 4914.533119353656, 4920.0, 3699.1307042845447, 3717.981410092932, 3759.6804271637216, 3770.45328427382, 3779.433085037266, 4986.551210391082, 5200.167973124433, 5209.322788037531, 5209.635325527328, 5238.319018527567, 4774.785134669018, 4809.547130963164, 4830.985493046359, 4837.612990960644, 4854.271859221004, 4854.611631069577, 4856.125897338513, 4864.318995340726, 4926.187179115406, 4936.802527898843, 4501.482329532015, 4728.834490792806, 4735.952640473552, 4743.881402964954, 4752.7623618818225, 4674.322223277673, 4764.904701906012, 4816.735079570657, 4857.975710121426, 4892.951070489392, 4698.0, 4752.405949256343, 4753.382060332273, 4755.215389460838, 4776.259679749644], "value": 3784.5631795530617, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.3909883357894, 682.0587589127007, 693.1197379328256, 746.7087835744061, 748.7267147491164, 11976.221545599541, 12657.30384828834, 12700.682482937931, 12741.61775146746, 12778.16941898776, 12832.358382080905, 12955.935220323909, 12998.41507132179, 13000.0, 13816.240106794123, 782.194339829976, 803.333233416263, 804.2319584796535, 804.3042768005662, 804.4409135650695, 622.4024936061387, 760.8592410404066, 763.8854171874208, 767.1024900866, 789.0413148025149, 822.4284122534841, 823.736404350608, 824.3858325547476, 824.6577670266832, 824.7525742277322, 820.2494717333633, 820.3970081989733, 820.4420993724265, 821.0270829067514, 822.4982760516081, 479.64266621367307, 588.2911092133968, 601.3475379213514, 603.3996173807091, 604.4650484321363, 13338.133021353678, 13359.198448093146, 13367.665808354868, 13402.262875541841, 13415.195088294733, 12337.568185113478, 12610.74778504434, 12795.296258705768, 12812.61562153137, 13192.86807131926, 11917.702057448547, 11924.344161071138, 11987.58043468483, 12262.754744905142, 12611.180273282207, 3592.3354179476883, 3600.9756583204844, 3627.310810945938, 3711.6288371162937, 3737.2151848111657, 61851.69074154645, 62204.68897655528, 62252.68873655648, 62919.6854015727, 63062.68468657673, 10039.378898685201, 10187.739520734918, 10294.948525257325, 10336.534855931352, 10648.946755266174, 18427.81572184288, 18493.5376615583, 18512.352067677566, 18531.258749649864, 18544.443666690022, 6821.0, 6931.722731090801, 7000.859982800417, 7065.964670176616, 7079.681414336367, 3478.4086705260165, 3667.02823751706, 3783.4135708965155, 3809.142942837839, 3826.2921359548573, 2548.1591074945595, 2642.841429514235, 2644.5504264274737, 2649.45686134342, 2650.2579277802247, 461.9630429565627, 475.5078493759012, 476.56870532168443, 478.6984199954076, 482.99517004830204, 460.49345719708725, 508.1919747601258, 524.9711265880337, 563.1777604697188, 567.3503838105346, 7891.408144389194, 8077.919220807834, 8389.790255243668, 8513.829723405497, 8585.613647385882, 571.5827445964469, 583.6906439587026, 586.5219845825638, 586.8562202260473, 593.7061154728351, 3914.197589494147, 3970.343921247772, 3989.401589761501, 4061.9796901015898, 4073.837046518165, 88671.11328886758, 88714.55642721875, 90639.0, 91751.0, 94182.0, 49010.75494622504, 49054.0, 49625.75187124041, 49703.0, 50758.746206268734, 58976.0, 71351.2864871355, 71560.64219678972, 71938.2806171942, 72858.63570682325, 7673.808154796175, 7687.730929417444, 7747.418943579141, 7771.378289736695, 7866.9213307866185, 44443.5556444353, 45011.774941125084, 45445.09109817851, 45568.08863822771, 46194.11901775379, 61592.0, 65716.34283657103, 65720.0, 66185.0, 66509.0, 9049.185573298435, 9068.138526839939, 9138.406003609743, 9168.083191680778, 9216.216621586971, 9989.550470229002, 10057.798844023226, 10255.179585633281, 10314.690559283234, 10491.213159012952, 8394.622241999003, 9405.717828465156, 9551.474668893277, 9711.0, 9838.36050656705, 11582.594609188638, 12212.694682633006, 12412.0, 12458.937705311415, 12609.87390126087, 12245.142840001114, 12409.317487538272, 12716.427760750974, 12909.031822613153, 12958.352082395653, 10378.0, 10745.731356716145, 10751.19366047534, 10803.567857285621, 10849.783004340026, 10615.522301496298, 10901.469597544785, 11091.0, 11109.333439993627, 11117.555297787998], "value": 6375.354952850586, "unit": "score"}], "name": "dromaeo_css", "value": 6293.186698931771, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 115.0, 114.0, 113.0, 117.0, 117.0, 113.0, 115.0, 116.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 122.0, 124.0, 170.0, 129.0, 124.0, 146.0, 127.0, 175.0, 123.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 162.0, 160.0, 160.0, 161.0, 160.0, 160.0, 161.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 111.0, 79.0, 78.0, 75.0, 76.0, 107.0, 76.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 121.0, 85.0, 85.0, 85.0, 117.0, 84.0, 85.0, 124.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 96.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 66.0, 65.0, 65.0, 65.0, 64.0, 62.0, 64.0, 64.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 64.0, 64.0, 64.0, 64.0, 64.0, 63.0, 64.0], "value": 63.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 79.0, 78.0, 79.0, 79.0, 78.0, 79.0, 79.0, 78.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 127.0, 136.0, 128.0, 129.0, 130.0, 131.0, 128.0, 206.0, 133.0], "value": 137.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 167.0, 170.0, 166.0, 166.0, 169.0, 164.0, 167.0, 164.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 66.0, 67.0, 65.0, 65.0, 67.0, 65.0, 68.0, 66.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1527.6, "alertThreshold": 2.0}]} 06:41:05 INFO - Return code: 0 06:41:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:41:05 INFO - # TBPL SUCCESS # 06:41:05 INFO - Running post-action listener: _resource_record_post_action 06:41:05 INFO - ##### 06:41:05 INFO - ##### Finished run-tests step (success) 06:41:05 INFO - ##### 06:41:05 INFO - Running post-run listener: _resource_record_post_run 06:41:05 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 536309760; Read time: 0; Write time: 441720 06:41:05 INFO - TinderboxPrint: CPU usage
13.0% 06:41:05 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 06:41:05 INFO - TinderboxPrint: I/O write bytes / time
536,309,760 / 441,720 06:41:05 INFO - TinderboxPrint: CPU idle
8,334.5 (87.0%) 06:41:05 INFO - TinderboxPrint: CPU user
1,202.1 (12.5%) 06:41:05 INFO - TinderboxPrint: Swap in / out
0 / 0 06:41:05 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246226944; Read time: 0; Write time: 242852 06:41:05 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 290082816; Read time: 0; Write time: 198868 06:41:05 INFO - Running post-run listener: _upload_blobber_files 06:41:05 INFO - Blob upload gear active. 06:41:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:41:05 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 06:41:05 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:41:05 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:41:06 INFO - (blobuploader) - INFO - Open directory for files ... 06:41:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:41:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:41:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:41:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:41:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:41:06 INFO - (blobuploader) - INFO - Done attempting. 06:41:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:41:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:41:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:41:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:41:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:41:14 INFO - (blobuploader) - INFO - Done attempting. 06:41:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:41:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:41:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:41:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:41:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:41:16 INFO - (blobuploader) - INFO - Done attempting. 06:41:16 INFO - (blobuploader) - INFO - Iteration through files over. 06:41:16 INFO - Return code: 0 06:41:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:41:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:41:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9f06722c81f8aa6ff132b5d4d3f325db064598c528df095e9c11af15d5f714cc1a59fff736dd32790ebed4f2ea58fa39c6534c7e6f3fe5d5a090ae521488b90b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2323f0f4e14175d9376d089caa751c86f958b3fd2c3baaf1d2b54841ea9c883656263255e08378461aa8c5d1924019e73e8c4d75f9539f26ffbdd7c62e7b09b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/420a4f6017fd634dd3174b93e473fa0b0357195e52e6f4904b36399c8ea5fb6e132be92dd9d20f87dd189f61123e8bd6e17597d43f2799fbbf1e6ab18a474a9d"} 06:41:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:41:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:41:16 INFO - Contents: 06:41:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9f06722c81f8aa6ff132b5d4d3f325db064598c528df095e9c11af15d5f714cc1a59fff736dd32790ebed4f2ea58fa39c6534c7e6f3fe5d5a090ae521488b90b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2323f0f4e14175d9376d089caa751c86f958b3fd2c3baaf1d2b54841ea9c883656263255e08378461aa8c5d1924019e73e8c4d75f9539f26ffbdd7c62e7b09b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/420a4f6017fd634dd3174b93e473fa0b0357195e52e6f4904b36399c8ea5fb6e132be92dd9d20f87dd189f61123e8bd6e17597d43f2799fbbf1e6ab18a474a9d"} 06:41:16 INFO - Running post-run listener: copy_logs_to_upload_dir 06:41:16 INFO - Copying logs to upload dir... 06:41:16 INFO - mkdir: /builds/slave/test/build/upload/logs 06:41:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.951788 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-07-12 06:41:15.963896) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 06:41:15.967724) ========= 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=4300f881736b422476174874000001bc-1468329315.267517-1773959525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9f06722c81f8aa6ff132b5d4d3f325db064598c528df095e9c11af15d5f714cc1a59fff736dd32790ebed4f2ea58fa39c6534c7e6f3fe5d5a090ae521488b90b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2323f0f4e14175d9376d089caa751c86f958b3fd2c3baaf1d2b54841ea9c883656263255e08378461aa8c5d1924019e73e8c4d75f9539f26ffbdd7c62e7b09b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/420a4f6017fd634dd3174b93e473fa0b0357195e52e6f4904b36399c8ea5fb6e132be92dd9d20f87dd189f61123e8bd6e17597d43f2799fbbf1e6ab18a474a9d"} build_url:https://queue.taskcluster.net/v1/task/ImXuKDWDTaytHzOplERjYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011398 build_url: 'https://queue.taskcluster.net/v1/task/ImXuKDWDTaytHzOplERjYA/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/Autoland-Non-PGO/sha512/9f06722c81f8aa6ff132b5d4d3f325db064598c528df095e9c11af15d5f714cc1a59fff736dd32790ebed4f2ea58fa39c6534c7e6f3fe5d5a090ae521488b90b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2323f0f4e14175d9376d089caa751c86f958b3fd2c3baaf1d2b54841ea9c883656263255e08378461aa8c5d1924019e73e8c4d75f9539f26ffbdd7c62e7b09b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/420a4f6017fd634dd3174b93e473fa0b0357195e52e6f4904b36399c8ea5fb6e132be92dd9d20f87dd189f61123e8bd6e17597d43f2799fbbf1e6ab18a474a9d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 06:41:15.998053) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:41:15.998419) ========= 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=4300f881736b422476174874000001bc-1468329315.267517-1773959525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:41:16.078448) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 06:41:16.078790) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 06:41:16.079105) ========= ========= Total master_lag: 0.25 =========